builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0188 starttime: 1486054385.15 results: success (0) buildid: 20170202073220 builduid: 4095e83c6fde4faebe12094d4c785b02 revision: fc352d66e7bce2a4d8635c1b8a815b62616420b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.149239) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.149662) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.149934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.176191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.176597) ========= 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.t5279XKgYk/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.loej6krHp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-02 08:53:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.23M=0.001s 2017-02-02 08:53:05 (9.23 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089079 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.289726) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.290045) ========= 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.t5279XKgYk/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.loej6krHp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.026826 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:05.338335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 08:53:05.338734) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc352d66e7bce2a4d8635c1b8a815b62616420b5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fc352d66e7bce2a4d8635c1b8a815b62616420b5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t5279XKgYk/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.loej6krHp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-02 08:53:05,441 truncating revision to first 12 chars 2017-02-02 08:53:05,441 Setting DEBUG logging. 2017-02-02 08:53:05,441 attempt 1/10 2017-02-02 08:53:05,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc352d66e7bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 08:53:06,461 unpacking tar archive at: mozilla-central-fc352d66e7bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.282769 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 08:53:06.644452) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:06.644770) ========= script_repo_revision: fc352d66e7bce2a4d8635c1b8a815b62616420b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:06.645141) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:06.645421) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 08:53:06.664909) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-02 08:53:06.665193) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.t5279XKgYk/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.loej6krHp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:53:06 INFO - MultiFileLogger online at 20170202 08:53:06 in /builds/slave/test 08:53:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:53:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:53:06 INFO - {'append_to_log': False, 08:53:06 INFO - 'base_work_dir': '/builds/slave/test', 08:53:06 INFO - 'blob_upload_branch': 'Firefox', 08:53:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:53:06 INFO - 'branch': 'Firefox', 08:53:06 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:06 INFO - 'config_files': ('talos/mac_config.py',), 08:53:06 INFO - 'default_actions': ('clobber', 08:53:06 INFO - 'read-buildbot-config', 08:53:06 INFO - 'download-and-extract', 08:53:06 INFO - 'populate-webroot', 08:53:06 INFO - 'create-virtualenv', 08:53:06 INFO - 'install', 08:53:06 INFO - 'run-tests'), 08:53:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:06 INFO - 'download_minidump_stackwalk': True, 08:53:06 INFO - 'download_symbols': 'ondemand', 08:53:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:53:06 INFO - 'tooltool.py': '/tools/tooltool.py', 08:53:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:53:06 INFO - '/tools/misc-python/virtualenv.py')}, 08:53:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:06 INFO - 'installer_path': 'installer.exe', 08:53:06 INFO - 'log_level': 'info', 08:53:06 INFO - 'log_name': 'talos', 08:53:06 INFO - 'log_to_console': True, 08:53:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:53:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:53:06 INFO - 'opt_config_files': (), 08:53:06 INFO - 'pip_index': False, 08:53:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:06 INFO - 'cmd': ('bash', 08:53:06 INFO - '-c', 08:53:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:06 INFO - 'enabled': True, 08:53:06 INFO - 'halt_on_failure': False, 08:53:06 INFO - 'name': 'check_screen_resolution'},), 08:53:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:06 INFO - 'cmd': ('bash', 08:53:06 INFO - '-c', 08:53:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:06 INFO - 'enabled': True, 08:53:06 INFO - 'halt_on_failure': False, 08:53:06 INFO - 'name': 'check_screen_resolution'},), 08:53:06 INFO - 'run_cmd_checks_enabled': True, 08:53:06 INFO - 'sps_profile': False, 08:53:06 INFO - 'sps_profile_interval': 0, 08:53:06 INFO - 'suite': 'dromaeojs-e10s', 08:53:06 INFO - 'system_bits': '32', 08:53:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:06 INFO - 'title': 't-yosemite-r7-0188', 08:53:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:06 INFO - 'use_talos_json': True, 08:53:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:53:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:06 INFO - 'work_dir': 'build'} 08:53:06 INFO - [mozharness: 2017-02-02 16:53:06.881125Z] Running clobber step. 08:53:06 INFO - Running pre-action listener: _resource_record_pre_action 08:53:06 INFO - Running main action method: clobber 08:53:06 INFO - rmtree: /builds/slave/test/build 08:53:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:53:09 INFO - Running post-action listener: _resource_record_post_action 08:53:09 INFO - [mozharness: 2017-02-02 16:53:09.370678Z] Finished clobber step (success) 08:53:09 INFO - [mozharness: 2017-02-02 16:53:09.370814Z] Running read-buildbot-config step. 08:53:09 INFO - Running pre-action listener: _resource_record_pre_action 08:53:09 INFO - Running main action method: read_buildbot_config 08:53:09 INFO - Using buildbot properties: 08:53:09 INFO - { 08:53:09 INFO - "project": "", 08:53:09 INFO - "product": "firefox", 08:53:09 INFO - "script_repo_revision": "production", 08:53:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:53:09 INFO - "repository": "", 08:53:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:53:09 INFO - "buildid": "20170202073220", 08:53:09 INFO - "pgo_build": "False", 08:53:09 INFO - "basedir": "/builds/slave/test", 08:53:09 INFO - "buildnumber": 63, 08:53:09 INFO - "slavename": "t-yosemite-r7-0188", 08:53:09 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:53:09 INFO - "platform": "yosemite_r7", 08:53:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:53:09 INFO - "branch": "mozilla-central", 08:53:09 INFO - "repo_path": "mozilla-central", 08:53:09 INFO - "revision": "fc352d66e7bce2a4d8635c1b8a815b62616420b5", 08:53:09 INFO - "stage_platform": "macosx64", 08:53:09 INFO - "builduid": "4095e83c6fde4faebe12094d4c785b02", 08:53:09 INFO - "slavebuilddir": "test" 08:53:09 INFO - } 08:53:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:53:09 INFO - Running post-action listener: _resource_record_post_action 08:53:09 INFO - [mozharness: 2017-02-02 16:53:09.374171Z] Finished read-buildbot-config step (success) 08:53:09 INFO - [mozharness: 2017-02-02 16:53:09.374262Z] Running download-and-extract step. 08:53:09 INFO - Running pre-action listener: _resource_record_pre_action 08:53:09 INFO - Running main action method: download_and_extract 08:53:09 INFO - mkdir: /builds/slave/test/build/tests 08:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:09 INFO - trying https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:53:09 INFO - Downloading https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:53:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 08:53:10 INFO - Downloaded 1199 bytes. 08:53:10 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:53:10 INFO - Using the following test package requirements: 08:53:10 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:53:10 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:53:10 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:53:10 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'jsshell-mac.zip'], 08:53:10 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:53:10 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:53:10 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:53:10 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:53:10 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:53:10 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:53:10 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:53:10 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:53:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:53:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:53:10 INFO - Fetch https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:53:11 INFO - Expected file size: 34848651 08:53:11 INFO - Obtained file size: 34848651 08:53:18 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:53:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:53:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:53:18 INFO - Fetch https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:53:20 INFO - Expected file size: 34848651 08:53:20 INFO - Obtained file size: 34848651 08:53:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:53:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:53:26 INFO - Fetch https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:53:27 INFO - Expected file size: 14403454 08:53:27 INFO - Obtained file size: 14403454 08:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:28 INFO - trying https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 08:53:30 INFO - Downloaded 70236078 bytes. 08:53:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:53:30 INFO - mkdir: /builds/slave/test/properties 08:53:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:53:30 INFO - Writing to file /builds/slave/test/properties/build_url 08:53:30 INFO - Contents: 08:53:30 INFO - build_url:https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:53:30 INFO - Running post-action listener: _resource_record_post_action 08:53:30 INFO - Running post-action listener: set_extra_try_arguments 08:53:30 INFO - [mozharness: 2017-02-02 16:53:30.974317Z] Finished download-and-extract step (success) 08:53:30 INFO - [mozharness: 2017-02-02 16:53:30.974441Z] Running populate-webroot step. 08:53:30 INFO - Running pre-action listener: _resource_record_pre_action 08:53:30 INFO - Running main action method: populate_webroot 08:53:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:30 INFO - '--tppagecycles', 08:53:30 INFO - '7'], 08:53:30 INFO - 'tests': ['tsvgm']}}, 08:53:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:53:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:53:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:53:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['damp', 'tps']}, 08:53:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'tests': ['damp', 'tps']}, 08:53:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['dromaeo_dom']}, 08:53:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['basic_compositor_video']}, 08:53:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:53:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['a11yr', 08:53:30 INFO - 'ts_paint', 08:53:30 INFO - 'tpaint', 08:53:30 INFO - 'sessionrestore', 08:53:30 INFO - 'sessionrestore_no_auto_restore', 08:53:30 INFO - 'tabpaint']}, 08:53:30 INFO - 'other-e10s': {'tests': ['a11yr', 08:53:30 INFO - 'ts_paint', 08:53:30 INFO - 'tpaint', 08:53:30 INFO - 'sessionrestore', 08:53:30 INFO - 'sessionrestore_no_auto_restore', 08:53:30 INFO - 'tabpaint']}, 08:53:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:53:30 INFO - 'ts_paint', 08:53:30 INFO - 'tpaint', 08:53:30 INFO - 'sessionrestore', 08:53:30 INFO - 'sessionrestore_no_auto_restore', 08:53:30 INFO - 'tabpaint']}, 08:53:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:53:30 INFO - 'ts_paint', 08:53:30 INFO - 'tpaint', 08:53:30 INFO - 'sessionrestore', 08:53:30 INFO - 'sessionrestore_no_auto_restore', 08:53:30 INFO - 'tabpaint']}, 08:53:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['a11yr', 08:53:30 INFO - 'ts_paint', 08:53:30 INFO - 'tpaint', 08:53:30 INFO - 'sessionrestore', 08:53:30 INFO - 'sessionrestore_no_auto_restore', 08:53:30 INFO - 'tabpaint']}, 08:53:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['a11yr', 08:53:30 INFO - 'ts_paint', 08:53:30 INFO - 'tpaint', 08:53:30 INFO - 'sessionrestore', 08:53:30 INFO - 'sessionrestore_no_auto_restore', 08:53:30 INFO - 'tabpaint']}, 08:53:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['tsvgx', 08:53:30 INFO - 'tsvgr_opacity', 08:53:30 INFO - 'tart', 08:53:30 INFO - 'tscrollx', 08:53:30 INFO - 'cart', 08:53:30 INFO - 'tsvg_static']}, 08:53:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:53:30 INFO - 'tsvgr_opacity', 08:53:30 INFO - 'tart', 08:53:30 INFO - 'tscrollx', 08:53:30 INFO - 'cart', 08:53:30 INFO - 'tsvg_static']}, 08:53:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'talos_options': ['--disable-e10s'], 08:53:30 INFO - 'tests': ['tp5o']}, 08:53:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:53:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'talos_options': ['--disable-e10s', 08:53:30 INFO - '--xperf_path', 08:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:30 INFO - 'tests': ['tp5n']}, 08:53:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:53:30 INFO - 'talos_options': ['--xperf_path', 08:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:30 INFO - 'tests': ['tp5n']}}, 08:53:30 INFO - 'talos.zip': {'path': '', 08:53:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:30 INFO - Running post-action listener: _resource_record_post_action 08:53:30 INFO - [mozharness: 2017-02-02 16:53:30.993343Z] Finished populate-webroot step (success) 08:53:30 INFO - [mozharness: 2017-02-02 16:53:30.993455Z] Running create-virtualenv step. 08:53:30 INFO - Running pre-action listener: _resource_record_pre_action 08:53:30 INFO - Running main action method: create_virtualenv 08:53:30 INFO - Creating virtualenv /builds/slave/test/build/venv 08:53:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:53:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:53:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:31 INFO - Using real prefix '/tools/python27' 08:53:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:53:31 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:34 INFO - Installing pip.................done. 08:53:34 INFO - Return code: 0 08:53:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:53:34 INFO - Reading from file tmpfile_stdout 08:53:34 INFO - Output received: 08:53:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:53:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:34 INFO - 'HOME': '/Users/cltbld', 08:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:34 INFO - 'LOGNAME': 'cltbld', 08:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:34 INFO - 'MOZ_NO_REMOTE': '1', 08:53:34 INFO - 'NO_EM_RESTART': '1', 08:53:34 INFO - 'PAGER': '/bin/cat', 08:53:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:34 INFO - 'PWD': '/builds/slave/test', 08:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:34 INFO - 'SHELL': '/bin/bash', 08:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:34 INFO - 'USER': 'cltbld', 08:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:34 INFO - 'XPC_FLAGS': '0x0', 08:53:34 INFO - 'XPC_SERVICE_NAME': '0', 08:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:53:35 INFO - Cleaning up... 08:53:35 INFO - Return code: 0 08:53:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:35 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 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:53:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:35 INFO - 'HOME': '/Users/cltbld', 08:53:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:35 INFO - 'LOGNAME': 'cltbld', 08:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:35 INFO - 'MOZ_NO_REMOTE': '1', 08:53:35 INFO - 'NO_EM_RESTART': '1', 08:53:35 INFO - 'PAGER': '/bin/cat', 08:53:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:35 INFO - 'PWD': '/builds/slave/test', 08:53:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:35 INFO - 'SHELL': '/bin/bash', 08:53:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:35 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:35 INFO - 'USER': 'cltbld', 08:53:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:35 INFO - 'XPC_FLAGS': '0x0', 08:53:35 INFO - 'XPC_SERVICE_NAME': '0', 08:53:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:35 INFO - Downloading/unpacking psutil>=3.1.1 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:38 INFO - Installing collected packages: psutil 08:53:38 INFO - Running setup.py install for psutil 08:53:39 INFO - building 'psutil._psutil_osx' extension 08:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:53:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:53:39 INFO - building 'psutil._psutil_posix' extension 08:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:53:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:39 INFO - ^ 08:53:39 INFO - 1 warning generated. 08:53:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:53:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:40 INFO - Successfully installed psutil 08:53:40 INFO - Cleaning up... 08:53:40 INFO - Return code: 0 08:53:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:40 INFO - 'HOME': '/Users/cltbld', 08:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:40 INFO - 'LOGNAME': 'cltbld', 08:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:40 INFO - 'MOZ_NO_REMOTE': '1', 08:53:40 INFO - 'NO_EM_RESTART': '1', 08:53:40 INFO - 'PAGER': '/bin/cat', 08:53:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:40 INFO - 'PWD': '/builds/slave/test', 08:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:40 INFO - 'SHELL': '/bin/bash', 08:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:40 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:40 INFO - 'USER': 'cltbld', 08:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:40 INFO - 'XPC_FLAGS': '0x0', 08:53:40 INFO - 'XPC_SERVICE_NAME': '0', 08:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:43 INFO - Installing collected packages: mozsystemmonitor 08:53:43 INFO - Running setup.py install for mozsystemmonitor 08:53:43 INFO - Successfully installed mozsystemmonitor 08:53:43 INFO - Cleaning up... 08:53:43 INFO - Return code: 0 08:53:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:43 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 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:43 INFO - 'HOME': '/Users/cltbld', 08:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:43 INFO - 'LOGNAME': 'cltbld', 08:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:43 INFO - 'MOZ_NO_REMOTE': '1', 08:53:43 INFO - 'NO_EM_RESTART': '1', 08:53:43 INFO - 'PAGER': '/bin/cat', 08:53:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:43 INFO - 'PWD': '/builds/slave/test', 08:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:43 INFO - 'SHELL': '/bin/bash', 08:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:43 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:43 INFO - 'USER': 'cltbld', 08:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:43 INFO - 'XPC_FLAGS': '0x0', 08:53:43 INFO - 'XPC_SERVICE_NAME': '0', 08:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:46 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:46 INFO - warning: no files found matching '*.txt' 08:53:46 INFO - no previously-included directories found matching 'build' 08:53:46 INFO - no previously-included directories found matching 'dist' 08:53:46 INFO - no previously-included directories found matching '.git*' 08:53:46 INFO - Installing collected packages: jsonschema, functools32 08:53:46 INFO - Running setup.py install for functools32 08:53:46 INFO - warning: no files found matching '*.txt' 08:53:46 INFO - no previously-included directories found matching 'build' 08:53:46 INFO - no previously-included directories found matching 'dist' 08:53:46 INFO - no previously-included directories found matching '.git*' 08:53:46 INFO - Successfully installed jsonschema functools32 08:53:46 INFO - Cleaning up... 08:53:46 INFO - Return code: 0 08:53:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:46 INFO - 'HOME': '/Users/cltbld', 08:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:46 INFO - 'LOGNAME': 'cltbld', 08:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:46 INFO - 'MOZ_NO_REMOTE': '1', 08:53:46 INFO - 'NO_EM_RESTART': '1', 08:53:46 INFO - 'PAGER': '/bin/cat', 08:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:46 INFO - 'PWD': '/builds/slave/test', 08:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:46 INFO - 'SHELL': '/bin/bash', 08:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:46 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:46 INFO - 'USER': 'cltbld', 08:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:46 INFO - 'XPC_FLAGS': '0x0', 08:53:46 INFO - 'XPC_SERVICE_NAME': '0', 08:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:47 INFO - Cleaning up... 08:53:47 INFO - Return code: 0 08:53:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:47 INFO - 'HOME': '/Users/cltbld', 08:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:47 INFO - 'LOGNAME': 'cltbld', 08:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:47 INFO - 'MOZ_NO_REMOTE': '1', 08:53:47 INFO - 'NO_EM_RESTART': '1', 08:53:47 INFO - 'PAGER': '/bin/cat', 08:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:47 INFO - 'PWD': '/builds/slave/test', 08:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:47 INFO - 'SHELL': '/bin/bash', 08:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:47 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:47 INFO - 'USER': 'cltbld', 08:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:47 INFO - 'XPC_FLAGS': '0x0', 08:53:47 INFO - 'XPC_SERVICE_NAME': '0', 08:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:47 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:50 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:51 INFO - Downloading docopt-0.6.1.tar.gz 08:53:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:51 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:51 INFO - Running setup.py install for blobuploader 08:53:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:51 INFO - Running setup.py install for requests 08:53:52 INFO - Running setup.py install for docopt 08:53:52 INFO - Successfully installed blobuploader requests docopt 08:53:52 INFO - Cleaning up... 08:53:52 INFO - Return code: 0 08:53:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:52 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 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:52 INFO - 'HOME': '/Users/cltbld', 08:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:52 INFO - 'LOGNAME': 'cltbld', 08:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:52 INFO - 'MOZ_NO_REMOTE': '1', 08:53:52 INFO - 'NO_EM_RESTART': '1', 08:53:52 INFO - 'PAGER': '/bin/cat', 08:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:52 INFO - 'PWD': '/builds/slave/test', 08:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:52 INFO - 'SHELL': '/bin/bash', 08:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:52 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:52 INFO - 'USER': 'cltbld', 08:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:52 INFO - 'XPC_FLAGS': '0x0', 08:53:52 INFO - 'XPC_SERVICE_NAME': '0', 08:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7Wy18x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-T5viun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7wper7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ApCl_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-6zLlfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-zu7VKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-UVTr2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-5LnPmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-mgHQI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-x2ixpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-okRAHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-6aM8Ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-3t1WmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-MSYHgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Fe_Bg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-KdtiFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-uAHfQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:54 INFO - Running setup.py install for manifestparser 08:53:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:54 INFO - Running setup.py install for mozcrash 08:53:54 INFO - Running setup.py install for mozdebug 08:53:54 INFO - Running setup.py install for mozdevice 08:53:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Running setup.py install for mozfile 08:53:55 INFO - Running setup.py install for mozhttpd 08:53:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Running setup.py install for mozinfo 08:53:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Running setup.py install for mozInstall 08:53:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:55 INFO - Running setup.py install for mozleak 08:53:55 INFO - Running setup.py install for mozlog 08:53:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Running setup.py install for moznetwork 08:53:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Running setup.py install for mozprocess 08:53:56 INFO - Running setup.py install for mozprofile 08:53:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Running setup.py install for mozrunner 08:53:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Running setup.py install for mozscreenshot 08:53:56 INFO - Running setup.py install for moztest 08:53:56 INFO - Running setup.py install for mozversion 08:53:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:57 INFO - Cleaning up... 08:53:57 INFO - Return code: 0 08:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:57 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 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:57 INFO - 'HOME': '/Users/cltbld', 08:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:57 INFO - 'LOGNAME': 'cltbld', 08:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:57 INFO - 'MOZ_NO_REMOTE': '1', 08:53:57 INFO - 'NO_EM_RESTART': '1', 08:53:57 INFO - 'PAGER': '/bin/cat', 08:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:57 INFO - 'PWD': '/builds/slave/test', 08:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:57 INFO - 'SHELL': '/bin/bash', 08:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:53:57 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:57 INFO - 'USER': 'cltbld', 08:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:57 INFO - 'XPC_FLAGS': '0x0', 08:53:57 INFO - 'XPC_SERVICE_NAME': '0', 08:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZvH5Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-5hUxyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-j7CqaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-_clqhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:57 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)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-DVCVyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-aJabUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-nPknRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-KkYnBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-L8hnAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-6vr3f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-YeRpPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-lBgwhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:58 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)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Bwqrtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-3jvFQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-aBir8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7xGoYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Vt8JDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:53:59 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)) 08:53:59 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)) 08:53:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:01 INFO - Downloading blessings-1.6.tar.gz 08:54:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:01 INFO - Installing collected packages: blessings 08:54:01 INFO - Running setup.py install for blessings 08:54:02 INFO - Successfully installed blessings 08:54:02 INFO - Cleaning up... 08:54:02 INFO - Return code: 0 08:54:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:02 INFO - Reading from file tmpfile_stdout 08:54:02 INFO - Current package versions: 08:54:02 INFO - blessings == 1.6 08:54:02 INFO - blobuploader == 1.2.4 08:54:02 INFO - docopt == 0.6.1 08:54:02 INFO - functools32 == 3.2.3-2 08:54:02 INFO - jsonschema == 2.5.1 08:54:02 INFO - manifestparser == 1.1 08:54:02 INFO - mozInstall == 1.12 08:54:02 INFO - mozcrash == 1.0 08:54:02 INFO - mozdebug == 0.1 08:54:02 INFO - mozdevice == 0.49 08:54:02 INFO - mozfile == 1.2 08:54:02 INFO - mozhttpd == 0.7 08:54:02 INFO - mozinfo == 0.9 08:54:02 INFO - mozleak == 0.1 08:54:02 INFO - mozlog == 3.4 08:54:02 INFO - moznetwork == 0.27 08:54:02 INFO - mozprocess == 0.24 08:54:02 INFO - mozprofile == 0.28 08:54:02 INFO - mozrunner == 6.13 08:54:02 INFO - mozscreenshot == 0.1 08:54:02 INFO - mozsystemmonitor == 0.3 08:54:02 INFO - moztest == 0.8 08:54:02 INFO - mozversion == 1.4 08:54:02 INFO - psutil == 3.1.1 08:54:02 INFO - requests == 1.2.3 08:54:02 INFO - wsgiref == 0.1.2 08:54:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:03 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 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:54:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:54:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:03 INFO - 'HOME': '/Users/cltbld', 08:54:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:03 INFO - 'LOGNAME': 'cltbld', 08:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:03 INFO - 'MOZ_NO_REMOTE': '1', 08:54:03 INFO - 'NO_EM_RESTART': '1', 08:54:03 INFO - 'PAGER': '/bin/cat', 08:54:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:03 INFO - 'PWD': '/builds/slave/test', 08:54:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:03 INFO - 'SHELL': '/bin/bash', 08:54:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:54:03 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:54:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:03 INFO - 'USER': 'cltbld', 08:54:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:03 INFO - 'XPC_FLAGS': '0x0', 08:54:03 INFO - 'XPC_SERVICE_NAME': '0', 08:54:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:54:03 INFO - Cleaning up... 08:54:03 INFO - Return code: 0 08:54:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54: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')]} 08:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54: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')]} 08:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c91b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0785bc8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, '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.t5279XKgYk/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.loej6krHp3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:54: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', 'jsonschema'] in /builds/slave/test/build 08:54: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 jsonschema 08:54:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:54:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:03 INFO - 'HOME': '/Users/cltbld', 08:54:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:03 INFO - 'LOGNAME': 'cltbld', 08:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:03 INFO - 'MOZ_NO_REMOTE': '1', 08:54:03 INFO - 'NO_EM_RESTART': '1', 08:54:03 INFO - 'PAGER': '/bin/cat', 08:54:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:03 INFO - 'PWD': '/builds/slave/test', 08:54:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:03 INFO - 'SHELL': '/bin/bash', 08:54:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:54:03 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:54:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:03 INFO - 'USER': 'cltbld', 08:54:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:03 INFO - 'XPC_FLAGS': '0x0', 08:54:03 INFO - 'XPC_SERVICE_NAME': '0', 08:54:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:04 INFO - Cleaning up... 08:54:04 INFO - Return code: 0 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - Running post-action listener: _start_resource_monitoring 08:54:04 INFO - Starting resource monitoring. 08:54:04 INFO - [mozharness: 2017-02-02 16:54:04.124686Z] Finished create-virtualenv step (success) 08:54:04 INFO - [mozharness: 2017-02-02 16:54:04.125652Z] Running install step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: install 08:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:04 INFO - Reading from file tmpfile_stdout 08:54:04 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:04 INFO - Reading from file tmpfile_stdout 08:54:04 INFO - Output received: 08:54:04 INFO - Usage: mozinstall [options] installer 08:54:04 INFO - Options: 08:54:04 INFO - -h, --help show this help message and exit 08:54:04 INFO - -d DEST, --destination=DEST 08:54:04 INFO - Directory to install application into. [default: 08:54:04 INFO - "/builds/slave/test"] 08:54:04 INFO - --app=APP Application being installed. [default: firefox] 08:54:04 INFO - mkdir: /builds/slave/test/build/application 08:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:54:26 INFO - Reading from file tmpfile_stdout 08:54:26 INFO - Output received: 08:54:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:54:26 INFO - Running post-action listener: _resource_record_post_action 08:54:26 INFO - [mozharness: 2017-02-02 16:54:26.318366Z] Finished install step (success) 08:54:26 INFO - [mozharness: 2017-02-02 16:54:26.318494Z] Running run-tests step. 08:54:26 INFO - Running pre-action listener: _resource_record_pre_action 08:54:26 INFO - Running main action method: run_tests 08:54:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:26 INFO - 2017-02-02 08:54:26.332 screenresolution[1627:7430] starting screenresolution argv=screenresolution get 08:54:26 INFO - 2017-02-02 08:54:26.335 screenresolution[1627:7430] Display 0: 1600x1200x32@60 08:54:26 INFO - 2017-02-02 08:54:26.341 screenresolution[1628:7433] starting screenresolution argv=screenresolution list 08:54:26 INFO - Available Modes on Display 0 08:54:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:26 INFO - Intel Iris: 08:54:26 INFO - Chipset Model: Intel Iris 08:54:26 INFO - Type: GPU 08:54:26 INFO - Bus: Built-In 08:54:26 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:26 INFO - Vendor: Intel (0x8086) 08:54:26 INFO - Device ID: 0x0a2e 08:54:26 INFO - Revision ID: 0x0009 08:54:26 INFO - Displays: 08:54:26 INFO - Display: 08:54:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:26 INFO - Display Serial Number: 1600x1200 60 08:54:26 INFO - Main Display: Yes 08:54:26 INFO - Mirror: Off 08:54:26 INFO - Online: Yes 08:54:26 INFO - Rotation: Supported 08:54:26 INFO - Return code: 0 08:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:26 INFO - Python 2.7.3 08:54:26 INFO - Return code: 0 08:54:26 INFO - grabbing minidump binary from tooltool 08:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:26 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 0x104a6fd30>, '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 0x104996db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a64be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:54:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:54:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:54:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:26 INFO - Return code: 0 08:54:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:54:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:54:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.t5279XKgYk/Render', 08:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:26 INFO - 'HOME': '/Users/cltbld', 08:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:26 INFO - 'LOGNAME': 'cltbld', 08:54:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:26 INFO - 'MOZ_NO_REMOTE': '1', 08:54:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:26 INFO - 'NO_EM_RESTART': '1', 08:54:26 INFO - 'PAGER': '/bin/cat', 08:54:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:26 INFO - 'PWD': '/builds/slave/test', 08:54:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:26 INFO - 'SHELL': '/bin/bash', 08:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.loej6krHp3/Listeners', 08:54:26 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:26 INFO - 'USER': 'cltbld', 08:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:26 INFO - 'XPC_FLAGS': '0x0', 08:54:26 INFO - 'XPC_SERVICE_NAME': '0', 08:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:54:26 INFO - mozversion application_buildid: 20170202073220 08:54:26 INFO - mozversion application_changeset: fc352d66e7bce2a4d8635c1b8a815b62616420b5 08:54:26 INFO - mozversion application_display_name: Nightly 08:54:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:26 INFO - mozversion application_name: Firefox 08:54:26 INFO - mozversion application_remotingname: firefox 08:54:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:54:26 INFO - mozversion application_vendor: Mozilla 08:54:26 INFO - mozversion application_version: 54.0a1 08:54:26 INFO - mozversion platform_buildid: 20170202073220 08:54:26 INFO - mozversion platform_changeset: fc352d66e7bce2a4d8635c1b8a815b62616420b5 08:54:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:54:26 INFO - mozversion platform_version: 54.0a1 08:54:26 INFO - using testdate: 1486054466 08:54:26 INFO - actual date: 1486054466 08:54:26 INFO - starting webserver on 'localhost:49242' 08:54:26 INFO - SUITE-START | Running 2 tests 08:54:26 INFO - TEST-START | dromaeo_css 08:54:26 INFO - operating with platform_type : mac_ 08:54:26 INFO - Initialising browser for dromaeo_css test... 08:54:26 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpfh2A0P/profile http://localhost:49242/getInfo.html) 08:54:29 INFO - PROCESS | 1638 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 08:54:30 INFO - PROCESS | 1638 | 2017-02-02 08:54:30.010 plugin-container[1640:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3b, name = 'com.apple.tsm.portname' 08:54:30 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:30 INFO - PROCESS | 1638 | 2017-02-02 08:54:30.013 plugin-container[1640:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b0b, name = 'com.apple.CFPasteboardClient' 08:54:30 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:30 INFO - PROCESS | 1638 | 2017-02-02 08:54:30.013 plugin-container[1640:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:30 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 08:54:30 INFO - PROCESS | 1638 | colorDepth:24 08:54:30 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 08:54:30 INFO - PROCESS | 1638 | __metrics 08:54:30 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3281: TypeError: this.tabs is undefined 08:54:30 INFO - PROCESS | 1638 | 1486054470160 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpfh2A0P/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpfh2A0P/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpfh2A0P/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpfh2A0P/profile/extensions/talos-powers@TalosPowersService.js:55:9 08:54:30 INFO - TEST-INFO | 1638: exit 0 08:54:30 INFO - Browser initialized. 08:54:30 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:30 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpfh2A0P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:33 INFO - PROCESS | 1641 | 2017-02-02 08:54:33.277 plugin-container[1642:7721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:54:33 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:33 INFO - PROCESS | 1641 | 2017-02-02 08:54:33.278 plugin-container[1642:7721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8953, name = 'com.apple.CFPasteboardClient' 08:54:33 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:54:33 INFO - PROCESS | 1641 | 2017-02-02 08:54:33.278 plugin-container[1642:7721] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:42 INFO - PROCESS | 1641 | RSS: Main: 233705472 08:54:42 INFO - PROCESS | 1641 | 08:57:45 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 08:57:47 INFO - PROCESS | 1641 | RSS: Main: 257593344 08:57:47 INFO - PROCESS | 1641 | 08:59:31 INFO - PROCESS | 1641 | ************************* 08:59:31 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 08:59:31 INFO - PROCESS | 1641 | 08:59:31 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:59:31 INFO - PROCESS | 1641 | Full stack: 08:59:31 INFO - PROCESS | 1641 | ************************* 09:00:49 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:00:50 INFO - PROCESS | 1641 | RSS: Main: 264097792 09:00:50 INFO - PROCESS | 1641 | 09:03:52 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:03:53 INFO - PROCESS | 1641 | RSS: Main: 274984960 09:03:53 INFO - PROCESS | 1641 | 09:06:54 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:06:55 INFO - PROCESS | 1641 | RSS: Main: 267898880 09:06:55 INFO - PROCESS | 1641 | 09:09:58 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:09:59 INFO - PROCESS | 1641 | RSS: Main: 272949248 09:09:59 INFO - PROCESS | 1641 | 09:13:01 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:13:02 INFO - PROCESS | 1641 | RSS: Main: 275197952 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | __start_tp_report 09:13:02 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 09:13:02 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 09:13:02 INFO - PROCESS | 1641 | |i|pagename|runs| 09:13:02 INFO - PROCESS | 1641 | |0;dojo.html;3336.632970373257;3387.1362802485364;3390.559227300452;3397.473391624299;3409.4544872820347;4323.848665296715;5567.610267281292;5644.20981062651;5651.9152212716845;5715.9428405715935;5555.138953462219;5683.488486036256;5699.116636921273;5708.571857110713;5741.224934633823;5133.743312834362;5140.691558506483;5161.4838516148275;5195.9480405195845;5272.604554658396;5179.8446046618665;5300.125479295912;5325.946740532584;5325.946740532603;5343.946560534384;5100.719460429675;5131.538161565477;5144.974275128638;5297.735113244323;5303.893922121555;5208.817691380821;5209.479052094798;5209.947900520984;5213.4525874782985;5213.452587478336;5084.44071152173;5123.564497017758;5131.461196574343;5198.506141916512;5200.141976573898;4356.281213599752;5559.777608895639;5654.293213348332;5669.659820410789;5752.2234498342605;5728.3126024876865;6142.84642883927;6187.504999600022;6190.350013248634;6217.191765070512;6083.270007599072;6141.486007784275;6144.3103146634585;6179.5674302798825;6180.598261113013;5892.145638882338;5898.439648233411;5903.3801450847905;5908.970455147697;5955.13650520672;6143;6243.656598887014;6245.812625621239;6249.875002500015;6281.089242059968;5971.671558064349;6030.7889223876955;6075.787347442818;6093.817185484442;6104.694765261719;6117.096718007469;6137.315698286874;6160.845978850564;6185.134081228632;6205.131281620576;6240.87810144553;6241.18864547615;6242.188515492957;6251.3123556408755;6260.593061450991;5797.01450753373;6034.913715531159;6204.565680402328;6263.906041409383;6272.749090036347;4855.053264613366;4894.657373983858;4902.8774280643265;4924.1875090610365;4992.201247800334;4415.161119387306;4416.24923762961;4457.331400289918;4483.641308695362;4490.393796837385;5829.621074630136;5841.88316233673;5853.268341457318;5857.384974577693;5862.941370586324;5233.607479439057;5944.138099975479;6146.832101900624;6149.84625384369;6205.03821907605;5713.600047996601;5776.3068431787615;5780.971095144581;5829.621074630136;5839.445252701072;5418.783248670088;5420.647657902224;5421.403645598981;5423.755930983115;5466.945330546644;6180.072989051679;6190.628562286277;6281.466075383643;6289.55973081889;6303.49572034227;6183.567150299616;6190.876182476415;6208.50331973432;6245.5628106032145;6255.53083518747;6260.499160067275;6301.495880329471;6303.4642055423965;6305.495560355069;6321.557490975588;5986.940130598812;6107.969460152671;6112.989854784201;6122.265328160649;6142.498775697988;6074.848128796815;6227.595206311576;6245.250569931455;6251.906221406592;6263.906041409291;6149.2313460817395;6153.3538978408415;6179.073139029181;6181.629102253879;6196.411340922605;5981.102834574848;6037.516998640011;6101.450869421773;6122.479589234965;6127.540434467343;5890;5976.193213916065;6017.338092809875;6029.57792954489;6065;6072.392760723892;6101.993171126892;6134.478569321479;6157.599756016024;6172.2901866284155;4950.702957822542;5746.712664366682;5960.612560183574;6027.879442411215;6030.819075427744 09:13:02 INFO - PROCESS | 1641 | |1;ext.html;451.85088920656165;478.7223410421956;482.9396325459318;494.3993048446137;526.1686535274266;39982.00044998873;42715.78642106789;42904.99999999996;44931.77534112333;44937.325940110924;43743.78128109363;44075;44178;44195.558044419624;44267.77866110665;8321.583920803965;9309.627614895397;9531.80936381272;11005.889941100606;11328.773424531504;11132.833007504853;11207.887921120806;11232.943835280812;11237.83143252852;11244.943775281112;11178.32930024197;11202.439878006067;11211.607593734221;11218.775624487545;11220.719482012933;11179.161562882775;11218.382988935606;11235.606753763579;11250.15623828208;11255.212135150543;9978.90021099787;11047.83428248577;11128.109751219963;11131.443427828657;11141.331520108739;37848.62151378478;38049.42925856114;38146.80926595377;38266.808665956494;38269.61730382688;3449.275652113059;3563.768355056913;3961.58403367648;3997.2405243003736;4016.979915100435;28610;29462.558061629094;29501.704982950112;29754;29899;3189.2983543620367;3195.6165260168927;3219.4365985952363;3303.339332133583;3309.8676052957853;468125;472186;474151;475686;477058;57952;58165.70917145387;58678.413215868146;59017;59086.40913590895;61130.083048754306;61282;61288;61460.078098828555;61512.38487615156;12731.299778512272;12787.232766034067;12817.35913204336;12895.16181448203;12928.676783080311;10971.067459265892;11314.547418103184;12044.578439754567;12250.039993600982;12301.815472767916;14072.577822665336;17054.80616356868;17136.40022599203;17162.656746864992;17175.31298748061;6449;7734.92265077342;7750.341220996165;7771.261730135628;7794.41541884361;758.859610971972;816.7911490993363;817.2889586060165;871.6513394642067;886.2245535156737;29087.127386178025;29478.41043179167;29729.702702973125;30119.849400752857;30246.84876575603;1054.9156067514734;1088.434014312569;1090.2422816142705;1093.2347356850237;1178.8821117888142;14940.402383904739;15338.463153789564;15517.068975861484;15806.525804225892;15848.049117052784;521022.39488802315;525308;526129;527906;528517;58350.124748128816;58764.70617646884;58884.7055764727;59081;59120.70439647774;1240.3922078181627;1273.707047379108;1384.5846246126123;1419.4038503828417;1438.4102517967583;18099.547511311797;24618.384540385916;28842;29128.854355728086;29211.85394073016;50283.24575131449;50716;50738;50763.23855142102;50875.49124508855;417136;418037;421487;421611;422076;26025.479490409707;26125.869370653785;26816.59775103414;26824.597631035158;26899;42926.49757258481;43195.56804432041;43369;43930.780346098065;44101.77949110234;35718;35781.28437431185;35836;36704.08239794026;37201;27553.897844086012;35226.647733523365;35566.288674225856;36666.63333366632;36684.89945301645;32490.83754581212;34242;34738.305233895684;35259.29481410409;35354.1161470955;30624;32131.839340803148;32305.51541726923;32349.67650323561;32551.511727323636;23745.05019799258;23757;24180.274591762274;24199.516009679355;24391.14630987907 09:13:02 INFO - PROCESS | 1641 | |2;jquery.html;395.9227950549644;398.8185375654078;406.6076236431843;409.167344454036;423.21493629317615;18865.622687546274;20146.992650367498;20816.791832081675;21545.784542154575;21638.91805409731;18065;20974.89512552439;21411.250606228787;21484.57030859386;21629.026693798776;5726.284214473191;5982.222311099551;5994.46049855513;6055.455009049185;6055.697215139247;5564.527015203692;5750.654960702351;5899.823005309848;5933.732982015819;5944.64332140073;5885.20549725788;5924.7333869975955;5932.703364831763;5939.316978547457;5940.19807326012;5699.857503562403;5850.473457388813;5910.202122713422;5926.348101708809;5959.225300710901;5141.228815677641;5144.125498665198;5160.303359046556;5169.276301317818;5173.508516690946;25282.98868045276;25986.22041338744;26120.86939565309;26262.6060609091;26590.73409265921;3113.548535462368;3114.2370119320913;3116.470200065994;3129;3169.35028319194;15364.769528457082;15492.767608485881;15666.53000409978;15761.211939402983;15842.049477031414;1559.7816305717208;1919.712043193532;1984.8610597258198;1987.175322241268;1995.1121750820892;139066;142880;144527.99999999895;145029;147371;11631.418429078512;11659.358735269472;11680.430305332813;11811.350375729246;11931.343776092224;12489.625311240678;12498.812517812241;14017.018808683295;14891;15082.924585377003;6337.985922252417;8396.496210227406;8419.579021048923;8449.493030418194;8589.699360522352;8074.878876816852;8229.753107406787;8231.629576669064;8382.652857199993;8390.03514595817;4573.702709323883;4645.837395691135;4649.442066951988;4719.9764001180465;4721.740304283228;1965.9606807863763;2005.3783327168624;2017.8890161041284;2023.7369142011444;2027.8276346510418;910.8451563234266;989.9554520046615;1009.914157296638;1011.8330475471465;1013.8428543575758;20169;21198.894005529874;21236.513444058768;21274.255401060887;21564.46088847791;1013.6350913671073;1018.2922868606396;1036.5594622285498;1048.250500891866;1050.2280823594651;8797.492714576316;9532.428054316762;10962.73928498217;11050.668479945614;11084.667459976214;592596;618404.8159518437;662214;738760;747240;125068.37465812612;130928;138794;140689;156898.2155089217;1003.433060320931;1014.8883622801486;1065.872095348563;1066.173715370594;1066.3441983180444;17462.91268543649;18468.907655461637;19858.702119468508;19963.301284454974;20010.599788003867;47313;47413.525864740914;58744.70627646834;59141.11288330593;60847;89486;90641.0935890659;90722.54638727028;91039.54480227556;91146.54426727824;8035.67857285702;8112.432129751097;8238.629261683118;8290.253877151084;8336.62485188156;12304;12505.874941250471;12618.242905425705;12741.299228542519;12850.55023074188;9222.40054396462;10707.732306692395;10915.181361398085;11153.83269250978;11318.434078296217;9066.22937050358;10715.624953126604;10837.699476062922;10906.873769328404;10922.617708380487;10457.215708821717;11012.751238384535;11061.391623460788;11312.434378281218;11459.770804584028;8005.199480051948;8065.758027259191;8305.83388332242;8429.452085614415;8458.450200736932;10691.572337106429;11022;11068.778624427627;11171.608993685182;11172.944135279271 09:13:02 INFO - PROCESS | 1641 | |3;mootools.html;1405.6907480354319;1423.6512054546638;1428.6428392901776;1442.1995792335254;1446.8987170898038;7232.674529646165;7849.48978316409;8069.112397636262;8488.363372747037;8491.108433614474;8016.599170041503;8216.055153657331;8283.5029898206;8376.246137847593;8414.410991230616;2240.383894429033;2294.8967296471615;2296.8966396512114;2302.838801283911;2305.7117860267467;2051.7230173926555;2124.4476436126643;2274.783895529922;2306.6770652108717;2315;2203.636399993999;2276.1236923784295;2276.3739971507803;2287.874166920819;2299.1033496936207;2276.3853759485014;2277.635578307471;2290.541891621682;2291.759365266648;2293.747687754346;1859.0890463672704;2189.430748005514;2192.495725983033;2199.626063569197;2200.658897870833;8426.368022398283;8479.448835825713;8485.703000394957;8502.617382217815;8508.44695094823;4968.503149685039;5115.590752739772;5118.411382691001;5122.795088196468;5134.589232861362;3320.5351250824906;3412.419888618916;3442.3287458945515;3463.757536972413;3477.1307173206696;1210.1831263896922;1215.3680086355134;1248.7689777391122;1251.418090587881;1269.3780047776615;9574.803149606369;10709.196810239186;10778.137748980063;10816.080633146192;10890.61882834097;4194.664426845845;4368.432103826546;4498.617617502484;4520.570545798144;4527.121244683485;8596.484210947363;8658.48049117055;8670.609822558;8686.435381700168;8696.21734043939;5105.5149760772665;5116.258142569307;5117.283580298761;5120.129577971754;5127.461616530285;4646.4191976003;4665.743384113906;4700.529947005272;4705.153072446992;4790.4251489821445;3653.06846754076;3698.907527311785;3715.256948610289;3735.2156047230387;3807.485989391452;1079.3739631013993;1110.6890070780194;1136.4829002803704;1139.680889350983;1167.2937872586963;746.3506749128293;749.5427789048676;782.1123025366251;803.2449497472475;846.8814365988767;5627.943720562824;5647.717614119278;5656.264685590847;5736.110902810071;5751.55736157617;936.639393833379;999.205631522941;1003.287665757306;1004.437514991607;1018.6638409324905;3588.6411358864425;3759.9060023499633;3809.2000679857542;3884.495015648004;3888.6694630956686;9551.713448596553;9977.80044399108;9999.100080992746;10015.69952901414;10029.448380339005;9903.702888913343;9904.554295056596;9958.253131015057;9973.80052398948;10000.699979000496;7417.740379086814;7783.805404864924;7785.3771698264;7828;7916.7229146980735;5134.306868572695;5554.333479982428;5757.4242575743765;5844.532437405082;5911.704414779329;8158.388120890838;8474.872876906975;8901.777455563662;8955;8956.193942545202;9055.547222638974;9115.635374585208;9143.588538515649;9262.814743705223;9285.07149285066;6000.759969601167;6011.278646562266;6267.96866015667;7194.964025179841;7220.855582888418;7711.11322197932;7774.494657847222;7814.4529882907655;7854.41091918097;7878.251566101211;6525.967370163119;7116.715331386687;7158.534695244793;7182.8563428730085;7241.13106427211;7027.086478757933;7046.330598593126;7109.111361079865;7183.173934997542;7193.964030179815;6363.204599425072;6409.8397540060005;6477.4170324671;6483.610983341015;6486.870262594627;4202.85290014848;4906.043321552405;4987.725675087905;5090.185570308733;5200.401953775364;5042.647014709082;5084.618653600921;5085.084684756779;5122.590192784643;5177.7670004849115 09:13:02 INFO - PROCESS | 1641 | |4;prototype.html;319.4186580423629;351.4833195203051;366.7286208205928;368.89486496348536;372.74466990111773;5039.848804535861;5194.779226881689;5512.366077901038;5582.888342233158;5633.155026745985;4240.130773191496;5130.846074617758;5391.487808658181;5400.434793473099;5523.226748255248;3488.82555872206;3761.793101379423;3999.6800255979597;4190;4236.809343579531;3885.7279990400684;4168.895777605569;4326.490635431458;4351.4343135392355;4375.256206444912;4110.321262470087;4112.362583799516;4132.235536425771;4133.532595928447;4162.729422587538;4126.381042843583;4137.2552940470705;4138.586141385867;4145.253854306224;4193.014641559231;3006.008017354268;3555.3422616815974;3582.6417358264225;3608.4767708682357;3619.7466177367596;5144.845654630367;5313.893722125575;5333.306670132897;5340.706261155635;5386.407495175527;4753.83361582343;4755.595774359183;4760.262159365304;4768.116593945372;4790.616750659974;4913.729744864031;4945.480724523909;5599.370655832381;5671.6597004179885;5872.911906321408;3557.491968162439;3720.2931443025736;3942;3951.2097580483733;3970.3845903884653;5082.695038297714;5337.412884582693;5794.159846822229;6176.938230617726;6445.681393466389;3326.9500957485657;4014.7754934745185;4019.658329042064;4022.758634481882;4023.7988100594853;3850.364689826203;4047.644039246826;4052.1490486997473;4067.28822456069;4074.4295798588223;4041.6362527372676;4733.5503127202865;4766.142094423037;4788.95211047892;4807.110684523374;5866.823995280149;5913.46778789911;5917.987884119334;5966.492848107872;5984.102384642339;1166.9824952625718;1247.6132398956352;1363.5091367107834;1411.287299913537;1440.9769063964702;970.1753509516855;988.19462138357;1007.9445630490246;1010.6260683546988;1018.2159737002614;4338.45984675442;4721.2767339921365;4939.950600493949;5273.126146250303;5301.840944771663;4983.377077865267;5395.70682459953;5718.313802343662;5798.739056742456;5850.577412213817;4497;4533.909321813611;4837.00841327527;5222.3472065991755;5222.451642577551;2620.5283049050972;2817.9151026854806;3033.8028028178096;3055.0834749575038;3061.112277439562;5039.622028347889;5081.745912704424;5186.807034382114;5914.935311643945;6010.3689112643415;5299.655522391032;5412.458754124556;5499.340079190522;5666.688332141689;5905;5900.200438866101;5908.379620139824;5908.409159084229;5912.260967379078;5932.940670593239;5245.868853278698;5531.04089312216;5611.354694210218;5677.886442271214;5740.68426236561;5933.851653708692;5982.641041537521;5995.340512543529;6001.879962400815;6007.849803754941;5538.695371754592;5778.024317476419;6289.980809211513;6293.332266949171;6336.683165841598;3879.650831425185;3962.227365663668;3990.541087774943;4016.718829681894;4038.293298672779;5262.315898933114;5426.484483974016;5740.598158128891;5984.611000284967;6093.634381937098;4843.903121937612;5141.511556402136;5157.381114266312;5419.539339156058;5832.24180856486;4719.961608446137;4961.107000739901;5446.564274857923;5849.444302791228;5855.648661080349;5240.947590524046;5395.649282796762;5651.745671444713;5676.16191904048;5758.2514273144225;5375.973120134374;5411.972940135274;5750.539956803529;5827;5871.324797648154;4819.373481447389;5078.339815823919;5322.787088516416;5615.634983726045;5696.458836410535 09:13:02 INFO - PROCESS | 1641 | |5;yui.html;695.2005194026868;698.4761428928304;716.5485743981293;776.4719990406521;827.102593685851;14537.636559086015;15619.687606247868;15629;15665.373385064599;15700.842991570094;14507;14664.12015279084;14718.337674804632;14738;14769.852301476982;635.5921633581615;674.2078058281525;851.8126012277305;868.2793281576309;870.1124852650292;688.2807466197835;791.5329955326356;864.8313578852126;865.8701194820765;876.5361845717634;680.571240118727;817.7301490508148;903.701778413122;929.6699671616581;939.9530023498797;910.7495438754329;930.3394589841221;930.6044930904407;932.7201839448138;932.8693982842408;681.9327752068048;689.0319101662172;692.2074225012333;694.135800927843;695.0304325465946;14707.485238016618;15052.32264548087;15211.771823422658;15284.082955022623;15378.846211537853;11941.761164776743;14674.853251467455;14684.632884177981;14752.631184220372;14767.261636918218;10041.949790251076;11677.532898684127;11769.411529423494;11810.586629468013;11877.731000219508;3737.0844143184822;3743.625637436262;3773.584905660375;3801.258754542865;3898.629630185128;74813.87779183316;91631;93753;94118;94119.0588094124;11245.550177992789;11251.718707032389;11313.717157070974;11397.97418232363;11581.42092895352;22933.770662293162;23215.883920580625;23300.76699232986;23318.533629327318;23320;7808.57052862098;7837.882431763528;7903.407244456689;7912;7940.28537431634;4237;4285.142971405731;4288.699791014599;4318.287482565342;4357.150355680676;3543.273628906068;3576.803275819803;3628.854845806138;3638.872639457606;3646.6535679110443;632.3897438971414;639.1052526462989;649.1787888321184;650.5251166648374;659.1200747002805;528.6959998001165;670.7149461478851;676.1075380497795;691.2879733874117;708.3518580498888;8398.454100483448;8734.65061397537;9873.506324683736;10172.135368493613;10435;417.09074218204603;429.276668813051;439.66146067527825;456.68260558911237;467.8900458392275;4412;4801.207800712843;4926.975365123223;5021.799128034911;5063.1645778446145;74023;92651;98876.01123988812;99382;101721;89274.66088008686;90663.54668226617;91175.63236551457;91750;92263;69636;69948.3521643144;70764.29235707577;71276.64361678055;71781.28218717746;9245.445273283625;10552.366857988545;11035.39305338182;11081.722956926142;11161.776764464828;46288.37990670445;48316.27525587049;48913.266301006224;48998.30509321879;50306;64315.88999045083;68128.6593567029;69015.30984690088;69551;70348;13132.671683207996;13154.276514791396;13236.602901912958;13345.332733363488;13603.727925441237;14848.554543363716;15025.69948601;15153.393864245747;15192.316345764246;15810.76283855766;13621.318934053059;13910.791338129733;14048.648783780487;14443.638909026939;14520.564383068524;13608.31958402056;13634.727305453636;14299.857001429853;14342;14378.209198494184;13403.396847141707;13421.463141474233;13668.384922678702;13829.239391833546;14161.787573186204;10760.946195268974;10774.515146818256;11167.162462815159;11329.660110196708;11703.005244553968;9450.716478505656;9568.61414738046;10845.566177352817;10941.945290273497;11080.61217857371 09:13:02 INFO - PROCESS | 1641 | __end_tp_report 09:13:02 INFO - PROCESS | 1641 | __start_cc_report 09:13:02 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,397 09:13:02 INFO - PROCESS | 1641 | __end_cc_report 09:13:02 INFO - PROCESS | 1641 | __startTimestamp1486055582020__endTimestamp 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | ------- Summary: start ------- 09:13:02 INFO - PROCESS | 1641 | Number of tests: 6 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5708.69 Median:5960.61 stddev:643.12 (10.8%) stddev-sans-first:617.58 09:13:02 INFO - PROCESS | 1641 | Values: 3336.6 3387.1 3390.6 3397.5 3409.5 4323.8 5567.6 5644.2 5651.9 5715.9 5555.1 5683.5 5699.1 5708.6 5741.2 5133.7 5140.7 5161.5 5195.9 5272.6 5179.8 5300.1 5325.9 5325.9 5343.9 5100.7 5131.5 5145.0 5297.7 5303.9 5208.8 5209.5 5209.9 5213.5 5213.5 5084.4 5123.6 5131.5 5198.5 5200.1 4356.3 5559.8 5654.3 5669.7 5752.2 5728.3 6142.8 6187.5 6190.4 6217.2 6083.3 6141.5 6144.3 6179.6 6180.6 5892.1 5898.4 5903.4 5909.0 5955.1 6143.0 6243.7 6245.8 6249.9 6281.1 5971.7 6030.8 6075.8 6093.8 6104.7 6117.1 6137.3 6160.8 6185.1 6205.1 6240.9 6241.2 6242.2 6251.3 6260.6 5797.0 6034.9 6204.6 6263.9 6272.7 4855.1 4894.7 4902.9 4924.2 4992.2 4415.2 4416.2 4457.3 4483.6 4490.4 5829.6 5841.9 5853.3 5857.4 5862.9 5233.6 5944.1 6146.8 6149.8 6205.0 5713.6 5776.3 5781.0 5829.6 5839.4 5418.8 5420.6 5421.4 5423.8 5466.9 6180.1 6190.6 6281.5 6289.6 6303.5 6183.6 6190.9 6208.5 6245.6 6255.5 6260.5 6301.5 6303.5 6305.5 6321.6 5986.9 6108.0 6113.0 6122.3 6142.5 6074.8 6227.6 6245.3 6251.9 6263.9 6149.2 6153.4 6179.1 6181.6 6196.4 5981.1 6037.5 6101.5 6122.5 6127.5 5890.0 5976.2 6017.3 6029.6 6065.0 6072.4 6102.0 6134.5 6157.6 6172.3 4950.7 5746.7 5960.6 6027.9 6030.8 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:61656.45 Median:34982.48 stddev:126240.02 (360.9%) stddev-sans-first:126510.52 09:13:02 INFO - PROCESS | 1641 | Values: 451.9 478.7 482.9 494.4 526.2 39982.0 42715.8 42905.0 44931.8 44937.3 43743.8 44075.0 44178.0 44195.6 44267.8 8321.6 9309.6 9531.8 11005.9 11328.8 11132.8 11207.9 11232.9 11237.8 11244.9 11178.3 11202.4 11211.6 11218.8 11220.7 11179.2 11218.4 11235.6 11250.2 11255.2 9978.9 11047.8 11128.1 11131.4 11141.3 37848.6 38049.4 38146.8 38266.8 38269.6 3449.3 3563.8 3961.6 3997.2 4017.0 28610.0 29462.6 29501.7 29754.0 29899.0 3189.3 3195.6 3219.4 3303.3 3309.9 468125.0 472186.0 474151.0 475686.0 477058.0 57952.0 58165.7 58678.4 59017.0 59086.4 61130.1 61282.0 61288.0 61460.1 61512.4 12731.3 12787.2 12817.4 12895.2 12928.7 10971.1 11314.5 12044.6 12250.0 12301.8 14072.6 17054.8 17136.4 17162.7 17175.3 6449.0 7734.9 7750.3 7771.3 7794.4 758.9 816.8 817.3 871.7 886.2 29087.1 29478.4 29729.7 30119.8 30246.8 1054.9 1088.4 1090.2 1093.2 1178.9 14940.4 15338.5 15517.1 15806.5 15848.0 521022.4 525308.0 526129.0 527906.0 528517.0 58350.1 58764.7 58884.7 59081.0 59120.7 1240.4 1273.7 1384.6 1419.4 1438.4 18099.5 24618.4 28842.0 29128.9 29211.9 50283.2 50716.0 50738.0 50763.2 50875.5 417136.0 418037.0 421487.0 421611.0 422076.0 26025.5 26125.9 26816.6 26824.6 26899.0 42926.5 43195.6 43369.0 43930.8 44101.8 35718.0 35781.3 35836.0 36704.1 37201.0 27553.9 35226.6 35566.3 36666.6 36684.9 32490.8 34242.0 34738.3 35259.3 35354.1 30624.0 32131.8 32305.5 32349.7 32551.5 23745.1 23757.0 24180.3 24199.5 24391.1 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:38637.20 Median:21555.12 stddev:112965.40 (524.1%) stddev-sans-first:113245.81 09:13:02 INFO - PROCESS | 1641 | Values: 395.9 398.8 406.6 409.2 423.2 18865.6 20147.0 20816.8 21545.8 21638.9 18065.0 20974.9 21411.3 21484.6 21629.0 5726.3 5982.2 5994.5 6055.5 6055.7 5564.5 5750.7 5899.8 5933.7 5944.6 5885.2 5924.7 5932.7 5939.3 5940.2 5699.9 5850.5 5910.2 5926.3 5959.2 5141.2 5144.1 5160.3 5169.3 5173.5 25283.0 25986.2 26120.9 26262.6 26590.7 3113.5 3114.2 3116.5 3129.0 3169.4 15364.8 15492.8 15666.5 15761.2 15842.0 1559.8 1919.7 1984.9 1987.2 1995.1 139066.0 142880.0 144528.0 145029.0 147371.0 11631.4 11659.4 11680.4 11811.4 11931.3 12489.6 12498.8 14017.0 14891.0 15082.9 6338.0 8396.5 8419.6 8449.5 8589.7 8074.9 8229.8 8231.6 8382.7 8390.0 4573.7 4645.8 4649.4 4720.0 4721.7 1966.0 2005.4 2017.9 2023.7 2027.8 910.8 990.0 1009.9 1011.8 1013.8 20169.0 21198.9 21236.5 21274.3 21564.5 1013.6 1018.3 1036.6 1048.3 1050.2 8797.5 9532.4 10962.7 11050.7 11084.7 592596.0 618404.8 662214.0 738760.0 747240.0 125068.4 130928.0 138794.0 140689.0 156898.2 1003.4 1014.9 1065.9 1066.2 1066.3 17462.9 18468.9 19858.7 19963.3 20010.6 47313.0 47413.5 58744.7 59141.1 60847.0 89486.0 90641.1 90722.5 91039.5 91146.5 8035.7 8112.4 8238.6 8290.3 8336.6 12304.0 12505.9 12618.2 12741.3 12850.6 9222.4 10707.7 10915.2 11153.8 11318.4 9066.2 10715.6 10837.7 10906.9 10922.6 10457.2 11012.8 11061.4 11312.4 11459.8 8005.2 8065.8 8305.8 8429.5 8458.5 10691.6 11022.0 11068.8 11171.6 11172.9 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5353.58 Median:5125.13 stddev:2904.48 (56.7%) stddev-sans-first:2897.47 09:13:02 INFO - PROCESS | 1641 | Values: 1405.7 1423.7 1428.6 1442.2 1446.9 7232.7 7849.5 8069.1 8488.4 8491.1 8016.6 8216.1 8283.5 8376.2 8414.4 2240.4 2294.9 2296.9 2302.8 2305.7 2051.7 2124.4 2274.8 2306.7 2315.0 2203.6 2276.1 2276.4 2287.9 2299.1 2276.4 2277.6 2290.5 2291.8 2293.7 1859.1 2189.4 2192.5 2199.6 2200.7 8426.4 8479.4 8485.7 8502.6 8508.4 4968.5 5115.6 5118.4 5122.8 5134.6 3320.5 3412.4 3442.3 3463.8 3477.1 1210.2 1215.4 1248.8 1251.4 1269.4 9574.8 10709.2 10778.1 10816.1 10890.6 4194.7 4368.4 4498.6 4520.6 4527.1 8596.5 8658.5 8670.6 8686.4 8696.2 5105.5 5116.3 5117.3 5120.1 5127.5 4646.4 4665.7 4700.5 4705.2 4790.4 3653.1 3698.9 3715.3 3735.2 3807.5 1079.4 1110.7 1136.5 1139.7 1167.3 746.4 749.5 782.1 803.2 846.9 5627.9 5647.7 5656.3 5736.1 5751.6 936.6 999.2 1003.3 1004.4 1018.7 3588.6 3759.9 3809.2 3884.5 3888.7 9551.7 9977.8 9999.1 10015.7 10029.4 9903.7 9904.6 9958.3 9973.8 10000.7 7417.7 7783.8 7785.4 7828.0 7916.7 5134.3 5554.3 5757.4 5844.5 5911.7 8158.4 8474.9 8901.8 8955.0 8956.2 9055.5 9115.6 9143.6 9262.8 9285.1 6000.8 6011.3 6268.0 7195.0 7220.9 7711.1 7774.5 7814.5 7854.4 7878.3 6526.0 7116.7 7158.5 7182.9 7241.1 7027.1 7046.3 7109.1 7183.2 7194.0 6363.2 6409.8 6477.4 6483.6 6486.9 4202.9 4906.0 4987.7 5090.2 5200.4 5042.6 5084.6 5085.1 5122.6 5177.8 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4617.22 Median:5082.22 stddev:1401.98 (27.6%) stddev-sans-first:1368.30 09:13:02 INFO - PROCESS | 1641 | Values: 319.4 351.5 366.7 368.9 372.7 5039.8 5194.8 5512.4 5582.9 5633.2 4240.1 5130.8 5391.5 5400.4 5523.2 3488.8 3761.8 3999.7 4190.0 4236.8 3885.7 4168.9 4326.5 4351.4 4375.3 4110.3 4112.4 4132.2 4133.5 4162.7 4126.4 4137.3 4138.6 4145.3 4193.0 3006.0 3555.3 3582.6 3608.5 3619.7 5144.8 5313.9 5333.3 5340.7 5386.4 4753.8 4755.6 4760.3 4768.1 4790.6 4913.7 4945.5 5599.4 5671.7 5872.9 3557.5 3720.3 3942.0 3951.2 3970.4 5082.7 5337.4 5794.2 6176.9 6445.7 3327.0 4014.8 4019.7 4022.8 4023.8 3850.4 4047.6 4052.1 4067.3 4074.4 4041.6 4733.6 4766.1 4789.0 4807.1 5866.8 5913.5 5918.0 5966.5 5984.1 1167.0 1247.6 1363.5 1411.3 1441.0 970.2 988.2 1007.9 1010.6 1018.2 4338.5 4721.3 4940.0 5273.1 5301.8 4983.4 5395.7 5718.3 5798.7 5850.6 4497.0 4533.9 4837.0 5222.3 5222.5 2620.5 2817.9 3033.8 3055.1 3061.1 5039.6 5081.7 5186.8 5914.9 6010.4 5299.7 5412.5 5499.3 5666.7 5905.0 5900.2 5908.4 5908.4 5912.3 5932.9 5245.9 5531.0 5611.4 5677.9 5740.7 5933.9 5982.6 5995.3 6001.9 6007.8 5538.7 5778.0 6290.0 6293.3 6336.7 3879.7 3962.2 3990.5 4016.7 4038.3 5262.3 5426.5 5740.6 5984.6 6093.6 4843.9 5141.5 5157.4 5419.5 5832.2 4720.0 4961.1 5446.6 5849.4 5855.6 5240.9 5395.6 5651.7 5676.2 5758.3 5376.0 5412.0 5750.5 5827.0 5871.3 4819.4 5078.3 5322.8 5615.6 5696.5 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19721.41 Median:4337.72 stddev:27191.64 (626.9%) stddev-sans-first:27230.39 09:13:02 INFO - PROCESS | 1641 | Values: 695.2 698.5 716.5 776.5 827.1 14537.6 15619.7 15629.0 15665.4 15700.8 14507.0 14664.1 14718.3 14738.0 14769.9 635.6 674.2 851.8 868.3 870.1 688.3 791.5 864.8 865.9 876.5 680.6 817.7 903.7 929.7 940.0 910.7 930.3 930.6 932.7 932.9 681.9 689.0 692.2 694.1 695.0 14707.5 15052.3 15211.8 15284.1 15378.8 11941.8 14674.9 14684.6 14752.6 14767.3 10041.9 11677.5 11769.4 11810.6 11877.7 3737.1 3743.6 3773.6 3801.3 3898.6 74813.9 91631.0 93753.0 94118.0 94119.1 11245.6 11251.7 11313.7 11398.0 11581.4 22933.8 23215.9 23300.8 23318.5 23320.0 7808.6 7837.9 7903.4 7912.0 7940.3 4237.0 4285.1 4288.7 4318.3 4357.2 3543.3 3576.8 3628.9 3638.9 3646.7 632.4 639.1 649.2 650.5 659.1 528.7 670.7 676.1 691.3 708.4 8398.5 8734.7 9873.5 10172.1 10435.0 417.1 429.3 439.7 456.7 467.9 4412.0 4801.2 4927.0 5021.8 5063.2 74023.0 92651.0 98876.0 99382.0 101721.0 89274.7 90663.5 91175.6 91750.0 92263.0 69636.0 69948.4 70764.3 71276.6 71781.3 9245.4 10552.4 11035.4 11081.7 11161.8 46288.4 48316.3 48913.3 48998.3 50306.0 64315.9 68128.7 69015.3 69551.0 70348.0 13132.7 13154.3 13236.6 13345.3 13603.7 14848.6 15025.7 15153.4 15192.3 15810.8 13621.3 13910.8 14048.6 14443.6 14520.6 13608.3 13634.7 14299.9 14342.0 14378.2 13403.4 13421.5 13668.4 13829.2 14161.8 10760.9 10774.5 11167.2 11329.7 11703.0 9450.7 9568.6 10845.6 10941.9 11080.6 09:13:02 INFO - PROCESS | 1641 | -------- Summary: end -------- 09:13:02 INFO - PROCESS | 1641 | 09:13:02 INFO - TEST-INFO | 1641: exit 0 09:13:02 INFO - TEST-OK | dromaeo_css | took 1115882ms 09:13:02 INFO - TEST-START | kraken 09:13:02 INFO - operating with platform_type : mac_ 09:13:02 INFO - Initialising browser for kraken test... 09:13:02 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpysNmN9/profile http://localhost:49242/getInfo.html) 09:13:05 INFO - PROCESS | 1652 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:13:05 INFO - PROCESS | 1652 | 2017-02-02 09:13:05.804 plugin-container[1653:9942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 09:13:05 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:05 INFO - PROCESS | 1652 | 2017-02-02 09:13:05.807 plugin-container[1653:9942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a57, name = 'com.apple.CFPasteboardClient' 09:13:05 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:05 INFO - PROCESS | 1652 | 2017-02-02 09:13:05.807 plugin-container[1653:9942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:05 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:13:05 INFO - PROCESS | 1652 | colorDepth:24 09:13:05 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:13:05 INFO - PROCESS | 1652 | __metrics 09:13:05 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3281: TypeError: this.tabs is undefined 09:13:05 INFO - PROCESS | 1652 | 1486055585947 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpysNmN9/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpysNmN9/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpysNmN9/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpysNmN9/profile/extensions/talos-powers@TalosPowersService.js:55:9 09:13:06 INFO - TEST-INFO | 1652: exit 0 09:13:06 INFO - Browser initialized. 09:13:06 INFO - Running cycle 1/1 for kraken test... 09:13:06 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpysNmN9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:08 INFO - PROCESS | 1654 | 2017-02-02 09:13:08.917 plugin-container[1655:10060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 09:13:08 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:08 INFO - PROCESS | 1654 | 2017-02-02 09:13:08.917 plugin-container[1655:10060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.CFPasteboardClient' 09:13:08 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:08 INFO - PROCESS | 1654 | 2017-02-02 09:13:08.918 plugin-container[1655:10060] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:18 INFO - PROCESS | 1654 | RSS: Main: 234217472 09:13:18 INFO - PROCESS | 1654 | 09:13:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:22 INFO - PROCESS | 1654 | RSS: Main: 247455744 09:13:22 INFO - PROCESS | 1654 | 09:13:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:13:25 INFO - PROCESS | 1654 | RSS: Main: 255148032 09:13:25 INFO - PROCESS | 1654 | 09:13:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:13:29 INFO - PROCESS | 1654 | RSS: Main: 257839104 09:13:29 INFO - PROCESS | 1654 | 09:13:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:13:32 INFO - PROCESS | 1654 | RSS: Main: 260325376 09:13:32 INFO - PROCESS | 1654 | 09:13:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:34 INFO - PROCESS | 1654 | RSS: Main: 260407296 09:13:34 INFO - PROCESS | 1654 | 09:13:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:38 INFO - PROCESS | 1654 | RSS: Main: 266678272 09:13:38 INFO - PROCESS | 1654 | 09:13:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:41 INFO - PROCESS | 1654 | RSS: Main: 266760192 09:13:41 INFO - PROCESS | 1654 | 09:13:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:13:45 INFO - PROCESS | 1654 | RSS: Main: 265617408 09:13:45 INFO - PROCESS | 1654 | 09:13:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:48 INFO - PROCESS | 1654 | RSS: Main: 264904704 09:13:48 INFO - PROCESS | 1654 | 09:13:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:50 INFO - PROCESS | 1654 | RSS: Main: 265035776 09:13:50 INFO - PROCESS | 1654 | 09:13:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:53 INFO - PROCESS | 1654 | RSS: Main: 265019392 09:13:53 INFO - PROCESS | 1654 | 09:13:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:57 INFO - PROCESS | 1654 | RSS: Main: 265551872 09:13:57 INFO - PROCESS | 1654 | 09:14:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:01 INFO - PROCESS | 1654 | RSS: Main: 262803456 09:14:01 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:14:03 INFO - PROCESS | 1654 | RSS: Main: 264753152 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | __start_tp_report 09:14:03 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:14:03 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:14:03 INFO - PROCESS | 1654 | |i|pagename|runs| 09:14:03 INFO - PROCESS | 1654 | |0;ai-astar;107;115;128;173;155;118;121;118;117;119 09:14:03 INFO - PROCESS | 1654 | |1;audio-beat-detection;142;114;111;142;113;110;111;114;132;111 09:14:03 INFO - PROCESS | 1654 | |2;audio-dft;196;210;199;220;207;197;199;197;200;197 09:14:03 INFO - PROCESS | 1654 | |3;audio-fft;81;59;62;76;67;74;87;81;93;77 09:14:03 INFO - PROCESS | 1654 | |4;audio-oscillator;88;72;87;74;70;72;83;96;59;104 09:14:03 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;88;90;87;90;87;95;102;112;129 09:14:03 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;97;96;99;150;94;95;95;96;97 09:14:03 INFO - PROCESS | 1654 | |7;imaging-desaturate;90;122;135;98;145;147;95;93;142;143 09:14:03 INFO - PROCESS | 1654 | |8;json-parse-financial;67;64;64;65;65;66;65;66;64;65 09:14:03 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;69;77;77;80;76;67;101;102;102;84 09:14:03 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;86;88;88;88;88;90;88;92;90;105 09:14:03 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;140;141;138;146;158;136;154;159;245;162 09:14:03 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;199;237;220;163;233;230;207;177;168;235 09:14:03 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;69;67;68;68;68;67;71;86;87 09:14:03 INFO - PROCESS | 1654 | __end_tp_report 09:14:03 INFO - PROCESS | 1654 | __start_cc_report 09:14:03 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,945 09:14:03 INFO - PROCESS | 1654 | __end_cc_report 09:14:03 INFO - PROCESS | 1654 | __startTimestamp1486055643330__endTimestamp 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:14:03 INFO - PROCESS | 1654 | Number of tests: 14 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:127.10 Median:120.00 stddev:20.57 (17.1%) stddev-sans-first:20.49 09:14:03 INFO - PROCESS | 1654 | Values: 107.0 115.0 128.0 173.0 155.0 118.0 121.0 118.0 117.0 119.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:114.00 stddev:13.23 (11.6%) stddev-sans-first:11.39 09:14:03 INFO - PROCESS | 1654 | Values: 142.0 114.0 111.0 142.0 113.0 110.0 111.0 114.0 132.0 111.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:202.20 Median:199.50 stddev:7.79 (3.9%) stddev-sans-first:7.93 09:14:03 INFO - PROCESS | 1654 | Values: 196.0 210.0 199.0 220.0 207.0 197.0 199.0 197.0 200.0 197.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:75.70 Median:79.00 stddev:10.70 (13.5%) stddev-sans-first:11.17 09:14:03 INFO - PROCESS | 1654 | Values: 81.0 59.0 62.0 76.0 67.0 74.0 87.0 81.0 93.0 77.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:78.50 stddev:13.57 (17.3%) stddev-sans-first:14.12 09:14:03 INFO - PROCESS | 1654 | Values: 88.0 72.0 87.0 74.0 70.0 72.0 83.0 96.0 59.0 104.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:89.00 stddev:13.77 (15.5%) stddev-sans-first:14.37 09:14:03 INFO - PROCESS | 1654 | Values: 90.0 88.0 90.0 87.0 90.0 87.0 95.0 102.0 112.0 129.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:101.50 Median:96.00 stddev:17.10 (17.8%) stddev-sans-first:18.02 09:14:03 INFO - PROCESS | 1654 | Values: 96.0 97.0 96.0 99.0 150.0 94.0 95.0 95.0 96.0 97.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:121.00 Median:118.50 stddev:24.32 (20.5%) stddev-sans-first:23.07 09:14:03 INFO - PROCESS | 1654 | Values: 90.0 122.0 135.0 98.0 145.0 147.0 95.0 93.0 142.0 143.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:0.99 (1.5%) stddev-sans-first:0.78 09:14:03 INFO - PROCESS | 1654 | Values: 67.0 64.0 64.0 65.0 65.0 66.0 65.0 66.0 64.0 65.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:83.50 Median:76.50 stddev:13.44 (17.6%) stddev-sans-first:13.20 09:14:03 INFO - PROCESS | 1654 | Values: 69.0 77.0 77.0 80.0 76.0 67.0 101.0 102.0 102.0 84.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:88.00 stddev:5.42 (6.2%) stddev-sans-first:5.52 09:14:03 INFO - PROCESS | 1654 | Values: 86.0 88.0 88.0 88.0 88.0 90.0 88.0 92.0 90.0 105.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:156.00 stddev:32.05 (20.5%) stddev-sans-first:33.33 09:14:03 INFO - PROCESS | 1654 | Values: 140.0 141.0 138.0 146.0 158.0 136.0 154.0 159.0 245.0 162.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.90 Median:225.00 stddev:28.83 (12.8%) stddev-sans-first:30.43 09:14:03 INFO - PROCESS | 1654 | Values: 199.0 237.0 220.0 163.0 233.0 230.0 207.0 177.0 168.0 235.0 09:14:03 INFO - PROCESS | 1654 | 09:14:03 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:68.50 stddev:7.78 (11.4%) stddev-sans-first:8.12 09:14:03 INFO - PROCESS | 1654 | Values: 68.0 69.0 67.0 68.0 68.0 68.0 67.0 71.0 86.0 87.0 09:14:03 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:14:03 INFO - PROCESS | 1654 | 09:14:04 INFO - TEST-INFO | 1654: exit 0 09:14:04 INFO - TEST-OK | kraken | took 61420ms 09:14:04 INFO - SUITE-END | took 1177s 09:14:04 INFO - Completed test suite (00:19:37) 09:14:04 INFO - javascript benchmark 09:14:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7268.947887690251, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.632970373257, 3387.1362802485364, 3390.559227300452, 3397.473391624299, 3409.4544872820347, 4323.848665296715, 5567.610267281292, 5644.20981062651, 5651.9152212716845, 5715.9428405715935, 5555.138953462219, 5683.488486036256, 5699.116636921273, 5708.571857110713, 5741.224934633823, 5133.743312834362, 5140.691558506483, 5161.4838516148275, 5195.9480405195845, 5272.604554658396, 5179.8446046618665, 5300.125479295912, 5325.946740532584, 5325.946740532603, 5343.946560534384, 5100.719460429675, 5131.538161565477, 5144.974275128638, 5297.735113244323, 5303.893922121555, 5208.817691380821, 5209.479052094798, 5209.947900520984, 5213.4525874782985, 5213.452587478336, 5084.44071152173, 5123.564497017758, 5131.461196574343, 5198.506141916512, 5200.141976573898, 4356.281213599752, 5559.777608895639, 5654.293213348332, 5669.659820410789, 5752.2234498342605, 5728.3126024876865, 6142.84642883927, 6187.504999600022, 6190.350013248634, 6217.191765070512, 6083.270007599072, 6141.486007784275, 6144.3103146634585, 6179.5674302798825, 6180.598261113013, 5892.145638882338, 5898.439648233411, 5903.3801450847905, 5908.970455147697, 5955.13650520672, 6143.0, 6243.656598887014, 6245.812625621239, 6249.875002500015, 6281.089242059968, 5971.671558064349, 6030.7889223876955, 6075.787347442818, 6093.817185484442, 6104.694765261719, 6117.096718007469, 6137.315698286874, 6160.845978850564, 6185.134081228632, 6205.131281620576, 6240.87810144553, 6241.18864547615, 6242.188515492957, 6251.3123556408755, 6260.593061450991, 5797.01450753373, 6034.913715531159, 6204.565680402328, 6263.906041409383, 6272.749090036347, 4855.053264613366, 4894.657373983858, 4902.8774280643265, 4924.1875090610365, 4992.201247800334, 4415.161119387306, 4416.24923762961, 4457.331400289918, 4483.641308695362, 4490.393796837385, 5829.621074630136, 5841.88316233673, 5853.268341457318, 5857.384974577693, 5862.941370586324, 5233.607479439057, 5944.138099975479, 6146.832101900624, 6149.84625384369, 6205.03821907605, 5713.600047996601, 5776.3068431787615, 5780.971095144581, 5829.621074630136, 5839.445252701072, 5418.783248670088, 5420.647657902224, 5421.403645598981, 5423.755930983115, 5466.945330546644, 6180.072989051679, 6190.628562286277, 6281.466075383643, 6289.55973081889, 6303.49572034227, 6183.567150299616, 6190.876182476415, 6208.50331973432, 6245.5628106032145, 6255.53083518747, 6260.499160067275, 6301.495880329471, 6303.4642055423965, 6305.495560355069, 6321.557490975588, 5986.940130598812, 6107.969460152671, 6112.989854784201, 6122.265328160649, 6142.498775697988, 6074.848128796815, 6227.595206311576, 6245.250569931455, 6251.906221406592, 6263.906041409291, 6149.2313460817395, 6153.3538978408415, 6179.073139029181, 6181.629102253879, 6196.411340922605, 5981.102834574848, 6037.516998640011, 6101.450869421773, 6122.479589234965, 6127.540434467343, 5890.0, 5976.193213916065, 6017.338092809875, 6029.57792954489, 6065.0, 6072.392760723892, 6101.993171126892, 6134.478569321479, 6157.599756016024, 6172.2901866284155, 4950.702957822542, 5746.712664366682, 5960.612560183574, 6027.879442411215, 6030.819075427744], "value": 5669.060671807269, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.85088920656165, 478.7223410421956, 482.9396325459318, 494.3993048446137, 526.1686535274266, 39982.00044998873, 42715.78642106789, 42904.99999999996, 44931.77534112333, 44937.325940110924, 43743.78128109363, 44075.0, 44178.0, 44195.558044419624, 44267.77866110665, 8321.583920803965, 9309.627614895397, 9531.80936381272, 11005.889941100606, 11328.773424531504, 11132.833007504853, 11207.887921120806, 11232.943835280812, 11237.83143252852, 11244.943775281112, 11178.32930024197, 11202.439878006067, 11211.607593734221, 11218.775624487545, 11220.719482012933, 11179.161562882775, 11218.382988935606, 11235.606753763579, 11250.15623828208, 11255.212135150543, 9978.90021099787, 11047.83428248577, 11128.109751219963, 11131.443427828657, 11141.331520108739, 37848.62151378478, 38049.42925856114, 38146.80926595377, 38266.808665956494, 38269.61730382688, 3449.275652113059, 3563.768355056913, 3961.58403367648, 3997.2405243003736, 4016.979915100435, 28610.0, 29462.558061629094, 29501.704982950112, 29754.0, 29899.0, 3189.2983543620367, 3195.6165260168927, 3219.4365985952363, 3303.339332133583, 3309.8676052957853, 468125.0, 472186.0, 474151.0, 475686.0, 477058.0, 57952.0, 58165.70917145387, 58678.413215868146, 59017.0, 59086.40913590895, 61130.083048754306, 61282.0, 61288.0, 61460.078098828555, 61512.38487615156, 12731.299778512272, 12787.232766034067, 12817.35913204336, 12895.16181448203, 12928.676783080311, 10971.067459265892, 11314.547418103184, 12044.578439754567, 12250.039993600982, 12301.815472767916, 14072.577822665336, 17054.80616356868, 17136.40022599203, 17162.656746864992, 17175.31298748061, 6449.0, 7734.92265077342, 7750.341220996165, 7771.261730135628, 7794.41541884361, 758.859610971972, 816.7911490993363, 817.2889586060165, 871.6513394642067, 886.2245535156737, 29087.127386178025, 29478.41043179167, 29729.702702973125, 30119.849400752857, 30246.84876575603, 1054.9156067514734, 1088.434014312569, 1090.2422816142705, 1093.2347356850237, 1178.8821117888142, 14940.402383904739, 15338.463153789564, 15517.068975861484, 15806.525804225892, 15848.049117052784, 521022.39488802315, 525308.0, 526129.0, 527906.0, 528517.0, 58350.124748128816, 58764.70617646884, 58884.7055764727, 59081.0, 59120.70439647774, 1240.3922078181627, 1273.707047379108, 1384.5846246126123, 1419.4038503828417, 1438.4102517967583, 18099.547511311797, 24618.384540385916, 28842.0, 29128.854355728086, 29211.85394073016, 50283.24575131449, 50716.0, 50738.0, 50763.23855142102, 50875.49124508855, 417136.0, 418037.0, 421487.0, 421611.0, 422076.0, 26025.479490409707, 26125.869370653785, 26816.59775103414, 26824.597631035158, 26899.0, 42926.49757258481, 43195.56804432041, 43369.0, 43930.780346098065, 44101.77949110234, 35718.0, 35781.28437431185, 35836.0, 36704.08239794026, 37201.0, 27553.897844086012, 35226.647733523365, 35566.288674225856, 36666.63333366632, 36684.89945301645, 32490.83754581212, 34242.0, 34738.305233895684, 35259.29481410409, 35354.1161470955, 30624.0, 32131.839340803148, 32305.51541726923, 32349.67650323561, 32551.511727323636, 23745.05019799258, 23757.0, 24180.274591762274, 24199.516009679355, 24391.14630987907], "value": 19402.335531289445, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.9227950549644, 398.8185375654078, 406.6076236431843, 409.167344454036, 423.21493629317615, 18865.622687546274, 20146.992650367498, 20816.791832081675, 21545.784542154575, 21638.91805409731, 18065.0, 20974.89512552439, 21411.250606228787, 21484.57030859386, 21629.026693798776, 5726.284214473191, 5982.222311099551, 5994.46049855513, 6055.455009049185, 6055.697215139247, 5564.527015203692, 5750.654960702351, 5899.823005309848, 5933.732982015819, 5944.64332140073, 5885.20549725788, 5924.7333869975955, 5932.703364831763, 5939.316978547457, 5940.19807326012, 5699.857503562403, 5850.473457388813, 5910.202122713422, 5926.348101708809, 5959.225300710901, 5141.228815677641, 5144.125498665198, 5160.303359046556, 5169.276301317818, 5173.508516690946, 25282.98868045276, 25986.22041338744, 26120.86939565309, 26262.6060609091, 26590.73409265921, 3113.548535462368, 3114.2370119320913, 3116.470200065994, 3129.0, 3169.35028319194, 15364.769528457082, 15492.767608485881, 15666.53000409978, 15761.211939402983, 15842.049477031414, 1559.7816305717208, 1919.712043193532, 1984.8610597258198, 1987.175322241268, 1995.1121750820892, 139066.0, 142880.0, 144527.99999999895, 145029.0, 147371.0, 11631.418429078512, 11659.358735269472, 11680.430305332813, 11811.350375729246, 11931.343776092224, 12489.625311240678, 12498.812517812241, 14017.018808683295, 14891.0, 15082.924585377003, 6337.985922252417, 8396.496210227406, 8419.579021048923, 8449.493030418194, 8589.699360522352, 8074.878876816852, 8229.753107406787, 8231.629576669064, 8382.652857199993, 8390.03514595817, 4573.702709323883, 4645.837395691135, 4649.442066951988, 4719.9764001180465, 4721.740304283228, 1965.9606807863763, 2005.3783327168624, 2017.8890161041284, 2023.7369142011444, 2027.8276346510418, 910.8451563234266, 989.9554520046615, 1009.914157296638, 1011.8330475471465, 1013.8428543575758, 20169.0, 21198.894005529874, 21236.513444058768, 21274.255401060887, 21564.46088847791, 1013.6350913671073, 1018.2922868606396, 1036.5594622285498, 1048.250500891866, 1050.2280823594651, 8797.492714576316, 9532.428054316762, 10962.73928498217, 11050.668479945614, 11084.667459976214, 592596.0, 618404.8159518437, 662214.0, 738760.0, 747240.0, 125068.37465812612, 130928.0, 138794.0, 140689.0, 156898.2155089217, 1003.433060320931, 1014.8883622801486, 1065.872095348563, 1066.173715370594, 1066.3441983180444, 17462.91268543649, 18468.907655461637, 19858.702119468508, 19963.301284454974, 20010.599788003867, 47313.0, 47413.525864740914, 58744.70627646834, 59141.11288330593, 60847.0, 89486.0, 90641.0935890659, 90722.54638727028, 91039.54480227556, 91146.54426727824, 8035.67857285702, 8112.432129751097, 8238.629261683118, 8290.253877151084, 8336.62485188156, 12304.0, 12505.874941250471, 12618.242905425705, 12741.299228542519, 12850.55023074188, 9222.40054396462, 10707.732306692395, 10915.181361398085, 11153.83269250978, 11318.434078296217, 9066.22937050358, 10715.624953126604, 10837.699476062922, 10906.873769328404, 10922.617708380487, 10457.215708821717, 11012.751238384535, 11061.391623460788, 11312.434378281218, 11459.770804584028, 8005.199480051948, 8065.758027259191, 8305.83388332242, 8429.452085614415, 8458.450200736932, 10691.572337106429, 11022.0, 11068.778624427627, 11171.608993685182, 11172.944135279271], "value": 9970.20520011761, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.6907480354319, 1423.6512054546638, 1428.6428392901776, 1442.1995792335254, 1446.8987170898038, 7232.674529646165, 7849.48978316409, 8069.112397636262, 8488.363372747037, 8491.108433614474, 8016.599170041503, 8216.055153657331, 8283.5029898206, 8376.246137847593, 8414.410991230616, 2240.383894429033, 2294.8967296471615, 2296.8966396512114, 2302.838801283911, 2305.7117860267467, 2051.7230173926555, 2124.4476436126643, 2274.783895529922, 2306.6770652108717, 2315.0, 2203.636399993999, 2276.1236923784295, 2276.3739971507803, 2287.874166920819, 2299.1033496936207, 2276.3853759485014, 2277.635578307471, 2290.541891621682, 2291.759365266648, 2293.747687754346, 1859.0890463672704, 2189.430748005514, 2192.495725983033, 2199.626063569197, 2200.658897870833, 8426.368022398283, 8479.448835825713, 8485.703000394957, 8502.617382217815, 8508.44695094823, 4968.503149685039, 5115.590752739772, 5118.411382691001, 5122.795088196468, 5134.589232861362, 3320.5351250824906, 3412.419888618916, 3442.3287458945515, 3463.757536972413, 3477.1307173206696, 1210.1831263896922, 1215.3680086355134, 1248.7689777391122, 1251.418090587881, 1269.3780047776615, 9574.803149606369, 10709.196810239186, 10778.137748980063, 10816.080633146192, 10890.61882834097, 4194.664426845845, 4368.432103826546, 4498.617617502484, 4520.570545798144, 4527.121244683485, 8596.484210947363, 8658.48049117055, 8670.609822558, 8686.435381700168, 8696.21734043939, 5105.5149760772665, 5116.258142569307, 5117.283580298761, 5120.129577971754, 5127.461616530285, 4646.4191976003, 4665.743384113906, 4700.529947005272, 4705.153072446992, 4790.4251489821445, 3653.06846754076, 3698.907527311785, 3715.256948610289, 3735.2156047230387, 3807.485989391452, 1079.3739631013993, 1110.6890070780194, 1136.4829002803704, 1139.680889350983, 1167.2937872586963, 746.3506749128293, 749.5427789048676, 782.1123025366251, 803.2449497472475, 846.8814365988767, 5627.943720562824, 5647.717614119278, 5656.264685590847, 5736.110902810071, 5751.55736157617, 936.639393833379, 999.205631522941, 1003.287665757306, 1004.437514991607, 1018.6638409324905, 3588.6411358864425, 3759.9060023499633, 3809.2000679857542, 3884.495015648004, 3888.6694630956686, 9551.713448596553, 9977.80044399108, 9999.100080992746, 10015.69952901414, 10029.448380339005, 9903.702888913343, 9904.554295056596, 9958.253131015057, 9973.80052398948, 10000.699979000496, 7417.740379086814, 7783.805404864924, 7785.3771698264, 7828.0, 7916.7229146980735, 5134.306868572695, 5554.333479982428, 5757.4242575743765, 5844.532437405082, 5911.704414779329, 8158.388120890838, 8474.872876906975, 8901.777455563662, 8955.0, 8956.193942545202, 9055.547222638974, 9115.635374585208, 9143.588538515649, 9262.814743705223, 9285.07149285066, 6000.759969601167, 6011.278646562266, 6267.96866015667, 7194.964025179841, 7220.855582888418, 7711.11322197932, 7774.494657847222, 7814.4529882907655, 7854.41091918097, 7878.251566101211, 6525.967370163119, 7116.715331386687, 7158.534695244793, 7182.8563428730085, 7241.13106427211, 7027.086478757933, 7046.330598593126, 7109.111361079865, 7183.173934997542, 7193.964030179815, 6363.204599425072, 6409.8397540060005, 6477.4170324671, 6483.610983341015, 6486.870262594627, 4202.85290014848, 4906.043321552405, 4987.725675087905, 5090.185570308733, 5200.401953775364, 5042.647014709082, 5084.618653600921, 5085.084684756779, 5122.590192784643, 5177.7670004849115], "value": 4346.380631800106, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.4186580423629, 351.4833195203051, 366.7286208205928, 368.89486496348536, 372.74466990111773, 5039.848804535861, 5194.779226881689, 5512.366077901038, 5582.888342233158, 5633.155026745985, 4240.130773191496, 5130.846074617758, 5391.487808658181, 5400.434793473099, 5523.226748255248, 3488.82555872206, 3761.793101379423, 3999.6800255979597, 4190.0, 4236.809343579531, 3885.7279990400684, 4168.895777605569, 4326.490635431458, 4351.4343135392355, 4375.256206444912, 4110.321262470087, 4112.362583799516, 4132.235536425771, 4133.532595928447, 4162.729422587538, 4126.381042843583, 4137.2552940470705, 4138.586141385867, 4145.253854306224, 4193.014641559231, 3006.008017354268, 3555.3422616815974, 3582.6417358264225, 3608.4767708682357, 3619.7466177367596, 5144.845654630367, 5313.893722125575, 5333.306670132897, 5340.706261155635, 5386.407495175527, 4753.83361582343, 4755.595774359183, 4760.262159365304, 4768.116593945372, 4790.616750659974, 4913.729744864031, 4945.480724523909, 5599.370655832381, 5671.6597004179885, 5872.911906321408, 3557.491968162439, 3720.2931443025736, 3942.0, 3951.2097580483733, 3970.3845903884653, 5082.695038297714, 5337.412884582693, 5794.159846822229, 6176.938230617726, 6445.681393466389, 3326.9500957485657, 4014.7754934745185, 4019.658329042064, 4022.758634481882, 4023.7988100594853, 3850.364689826203, 4047.644039246826, 4052.1490486997473, 4067.28822456069, 4074.4295798588223, 4041.6362527372676, 4733.5503127202865, 4766.142094423037, 4788.95211047892, 4807.110684523374, 5866.823995280149, 5913.46778789911, 5917.987884119334, 5966.492848107872, 5984.102384642339, 1166.9824952625718, 1247.6132398956352, 1363.5091367107834, 1411.287299913537, 1440.9769063964702, 970.1753509516855, 988.19462138357, 1007.9445630490246, 1010.6260683546988, 1018.2159737002614, 4338.45984675442, 4721.2767339921365, 4939.950600493949, 5273.126146250303, 5301.840944771663, 4983.377077865267, 5395.70682459953, 5718.313802343662, 5798.739056742456, 5850.577412213817, 4497.0, 4533.909321813611, 4837.00841327527, 5222.3472065991755, 5222.451642577551, 2620.5283049050972, 2817.9151026854806, 3033.8028028178096, 3055.0834749575038, 3061.112277439562, 5039.622028347889, 5081.745912704424, 5186.807034382114, 5914.935311643945, 6010.3689112643415, 5299.655522391032, 5412.458754124556, 5499.340079190522, 5666.688332141689, 5905.0, 5900.200438866101, 5908.379620139824, 5908.409159084229, 5912.260967379078, 5932.940670593239, 5245.868853278698, 5531.04089312216, 5611.354694210218, 5677.886442271214, 5740.68426236561, 5933.851653708692, 5982.641041537521, 5995.340512543529, 6001.879962400815, 6007.849803754941, 5538.695371754592, 5778.024317476419, 6289.980809211513, 6293.332266949171, 6336.683165841598, 3879.650831425185, 3962.227365663668, 3990.541087774943, 4016.718829681894, 4038.293298672779, 5262.315898933114, 5426.484483974016, 5740.598158128891, 5984.611000284967, 6093.634381937098, 4843.903121937612, 5141.511556402136, 5157.381114266312, 5419.539339156058, 5832.24180856486, 4719.961608446137, 4961.107000739901, 5446.564274857923, 5849.444302791228, 5855.648661080349, 5240.947590524046, 5395.649282796762, 5651.745671444713, 5676.16191904048, 5758.2514273144225, 5375.973120134374, 5411.972940135274, 5750.539956803529, 5827.0, 5871.324797648154, 4819.373481447389, 5078.339815823919, 5322.787088516416, 5615.634983726045, 5696.458836410535], "value": 4188.855148004792, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.2005194026868, 698.4761428928304, 716.5485743981293, 776.4719990406521, 827.102593685851, 14537.636559086015, 15619.687606247868, 15629.0, 15665.373385064599, 15700.842991570094, 14507.0, 14664.12015279084, 14718.337674804632, 14738.0, 14769.852301476982, 635.5921633581615, 674.2078058281525, 851.8126012277305, 868.2793281576309, 870.1124852650292, 688.2807466197835, 791.5329955326356, 864.8313578852126, 865.8701194820765, 876.5361845717634, 680.571240118727, 817.7301490508148, 903.701778413122, 929.6699671616581, 939.9530023498797, 910.7495438754329, 930.3394589841221, 930.6044930904407, 932.7201839448138, 932.8693982842408, 681.9327752068048, 689.0319101662172, 692.2074225012333, 694.135800927843, 695.0304325465946, 14707.485238016618, 15052.32264548087, 15211.771823422658, 15284.082955022623, 15378.846211537853, 11941.761164776743, 14674.853251467455, 14684.632884177981, 14752.631184220372, 14767.261636918218, 10041.949790251076, 11677.532898684127, 11769.411529423494, 11810.586629468013, 11877.731000219508, 3737.0844143184822, 3743.625637436262, 3773.584905660375, 3801.258754542865, 3898.629630185128, 74813.87779183316, 91631.0, 93753.0, 94118.0, 94119.0588094124, 11245.550177992789, 11251.718707032389, 11313.717157070974, 11397.97418232363, 11581.42092895352, 22933.770662293162, 23215.883920580625, 23300.76699232986, 23318.533629327318, 23320.0, 7808.57052862098, 7837.882431763528, 7903.407244456689, 7912.0, 7940.28537431634, 4237.0, 4285.142971405731, 4288.699791014599, 4318.287482565342, 4357.150355680676, 3543.273628906068, 3576.803275819803, 3628.854845806138, 3638.872639457606, 3646.6535679110443, 632.3897438971414, 639.1052526462989, 649.1787888321184, 650.5251166648374, 659.1200747002805, 528.6959998001165, 670.7149461478851, 676.1075380497795, 691.2879733874117, 708.3518580498888, 8398.454100483448, 8734.65061397537, 9873.506324683736, 10172.135368493613, 10435.0, 417.09074218204603, 429.276668813051, 439.66146067527825, 456.68260558911237, 467.8900458392275, 4412.0, 4801.207800712843, 4926.975365123223, 5021.799128034911, 5063.1645778446145, 74023.0, 92651.0, 98876.01123988812, 99382.0, 101721.0, 89274.66088008686, 90663.54668226617, 91175.63236551457, 91750.0, 92263.0, 69636.0, 69948.3521643144, 70764.29235707577, 71276.64361678055, 71781.28218717746, 9245.445273283625, 10552.366857988545, 11035.39305338182, 11081.722956926142, 11161.776764464828, 46288.37990670445, 48316.27525587049, 48913.266301006224, 48998.30509321879, 50306.0, 64315.88999045083, 68128.6593567029, 69015.30984690088, 69551.0, 70348.0, 13132.671683207996, 13154.276514791396, 13236.602901912958, 13345.332733363488, 13603.727925441237, 14848.554543363716, 15025.69948601, 15153.393864245747, 15192.316345764246, 15810.76283855766, 13621.318934053059, 13910.791338129733, 14048.648783780487, 14443.638909026939, 14520.564383068524, 13608.31958402056, 13634.727305453636, 14299.857001429853, 14342.0, 14378.209198494184, 13403.396847141707, 13421.463141474233, 13668.384922678702, 13829.239391833546, 14161.787573186204, 10760.946195268974, 10774.515146818256, 11167.162462815159, 11329.660110196708, 11703.005244553968, 9450.716478505656, 9568.61414738046, 10845.566177352817, 10941.945290273497, 11080.61217857371], "value": 7387.382279939977, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 128.0, 173.0, 155.0, 118.0, 121.0, 118.0, 117.0, 119.0], "value": 127.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 111.0, 142.0, 113.0, 110.0, 111.0, 114.0, 132.0, 111.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 210.0, 199.0, 220.0, 207.0, 197.0, 199.0, 197.0, 200.0, 197.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 62.0, 76.0, 67.0, 74.0, 87.0, 81.0, 93.0, 77.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 87.0, 74.0, 70.0, 72.0, 83.0, 96.0, 59.0, 104.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 87.0, 90.0, 87.0, 95.0, 102.0, 112.0, 129.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 99.0, 150.0, 94.0, 95.0, 95.0, 96.0, 97.0], "value": 101.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 122.0, 135.0, 98.0, 145.0, 147.0, 95.0, 93.0, 142.0, 143.0], "value": 121.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 65.0, 65.0, 66.0, 65.0, 66.0, 64.0, 65.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 77.0, 80.0, 76.0, 67.0, 101.0, 102.0, 102.0, 84.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 88.0, 88.0, 88.0, 90.0, 88.0, 92.0, 90.0, 105.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 138.0, 146.0, 158.0, 136.0, 154.0, 159.0, 245.0, 162.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 237.0, 220.0, 163.0, 233.0, 230.0, 207.0, 177.0, 168.0, 235.0], "value": 206.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 68.0, 68.0, 67.0, 71.0, 86.0, 87.0], "value": 71.9, "unit": "score"}]}]} 09:14:04 INFO - Return code: 0 09:14:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:04 ERROR - # TBPL SUCCESS # 09:14:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:04 INFO - 2017-02-02 09:14:04.658 screenresolution[1659:10445] starting screenresolution argv=screenresolution get 09:14:04 INFO - 2017-02-02 09:14:04.661 screenresolution[1659:10445] Display 0: 1600x1200x32@60 09:14:04 INFO - 2017-02-02 09:14:04.667 screenresolution[1660:10448] starting screenresolution argv=screenresolution list 09:14:04 INFO - Available Modes on Display 0 09:14:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:04 INFO - Intel Iris: 09:14:04 INFO - Chipset Model: Intel Iris 09:14:04 INFO - Type: GPU 09:14:04 INFO - Bus: Built-In 09:14:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:04 INFO - Vendor: Intel (0x8086) 09:14:04 INFO - Device ID: 0x0a2e 09:14:04 INFO - Revision ID: 0x0009 09:14:04 INFO - Displays: 09:14:04 INFO - Display: 09:14:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:04 INFO - Display Serial Number: 1600x1200 60 09:14:04 INFO - Main Display: Yes 09:14:04 INFO - Mirror: Off 09:14:04 INFO - Online: Yes 09:14:04 INFO - Rotation: Supported 09:14:04 INFO - Return code: 0 09:14:04 INFO - Running post-action listener: _resource_record_post_action 09:14:04 INFO - [mozharness: 2017-02-02 17:14:04.904473Z] Finished run-tests step (success) 09:14:04 INFO - Running post-run listener: _resource_record_post_run 09:14:04 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 40245760; Write bytes: 557223424; Read time: 400; Write time: 1717 09:14:04 INFO - TinderboxPrint: CPU usage
54.7% 09:14:04 INFO - TinderboxPrint: I/O read bytes / time
40,245,760 / 400 09:14:04 INFO - TinderboxPrint: I/O write bytes / time
557,223,424 / 1,717 09:14:04 INFO - TinderboxPrint: CPU idle
2,175.6 (45.3%) 09:14:04 INFO - TinderboxPrint: CPU system
970.1 (20.2%) 09:14:04 INFO - TinderboxPrint: CPU user
1,655.9 (34.5%) 09:14:04 INFO - TinderboxPrint: Swap in / out
291,708,928 / 0 09:14:04 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250950144; Write bytes: 245073920; Read time: 18053; Write time: 484 09:14:04 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36141568; Write bytes: 300762624; Read time: 367; Write time: 1201 09:14:05 INFO - Running post-run listener: _upload_blobber_files 09:14:05 INFO - Blob upload gear active. 09:14:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:14:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:06 INFO - (blobuploader) - INFO - Done attempting. 09:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:08 INFO - (blobuploader) - INFO - Done attempting. 09:14:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:14:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:14:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:09 INFO - (blobuploader) - INFO - Done attempting. 09:14:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:11 INFO - (blobuploader) - INFO - Done attempting. 09:14:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:11 INFO - Return code: 0 09:14:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12e75e36af8562aa18917f5a2f8dea336f67622f73fa8eefd8765fdeea9bf085ae08479d96c62e9973aca7334c928af2f2af9cfee6ea308e56794d663882402a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83de687f8ee2fe189954430defc66693c457ffe55dd4558f6337a556ab1850795e0986d11dc2004a908936902f238fef5b81ba297f528d9625d9d37a77b5e962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af4869a63bc3c2836a9eb049dae5b5d0b9416323f1ac28f937dd07a527870a332ff7744a390e92521f988fb389b8e5d8aabe74b3d8778f06e67ab4973315a8a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc067558d258741c596b44f583c476dab848833863a1f93026c63059158e9291fe3f00d8c3ec9268d0acd1fa5fcb200a878cfcc8820465de0a673f0c44671a71"} 09:14:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:11 INFO - Contents: 09:14:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12e75e36af8562aa18917f5a2f8dea336f67622f73fa8eefd8765fdeea9bf085ae08479d96c62e9973aca7334c928af2f2af9cfee6ea308e56794d663882402a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83de687f8ee2fe189954430defc66693c457ffe55dd4558f6337a556ab1850795e0986d11dc2004a908936902f238fef5b81ba297f528d9625d9d37a77b5e962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af4869a63bc3c2836a9eb049dae5b5d0b9416323f1ac28f937dd07a527870a332ff7744a390e92521f988fb389b8e5d8aabe74b3d8778f06e67ab4973315a8a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc067558d258741c596b44f583c476dab848833863a1f93026c63059158e9291fe3f00d8c3ec9268d0acd1fa5fcb200a878cfcc8820465de0a673f0c44671a71"} 09:14:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:11 INFO - Copying logs to upload dir... 09:14:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.164252 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-02 09:14:11.882677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 09:14:11.885825) ========= 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.t5279XKgYk/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.loej6krHp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/Firefox/sha512/12e75e36af8562aa18917f5a2f8dea336f67622f73fa8eefd8765fdeea9bf085ae08479d96c62e9973aca7334c928af2f2af9cfee6ea308e56794d663882402a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83de687f8ee2fe189954430defc66693c457ffe55dd4558f6337a556ab1850795e0986d11dc2004a908936902f238fef5b81ba297f528d9625d9d37a77b5e962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af4869a63bc3c2836a9eb049dae5b5d0b9416323f1ac28f937dd07a527870a332ff7744a390e92521f988fb389b8e5d8aabe74b3d8778f06e67ab4973315a8a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc067558d258741c596b44f583c476dab848833863a1f93026c63059158e9291fe3f00d8c3ec9268d0acd1fa5fcb200a878cfcc8820465de0a673f0c44671a71"} build_url:https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/DxwbEgLcTRqU6Zo8nZE2hA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12e75e36af8562aa18917f5a2f8dea336f67622f73fa8eefd8765fdeea9bf085ae08479d96c62e9973aca7334c928af2f2af9cfee6ea308e56794d663882402a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83de687f8ee2fe189954430defc66693c457ffe55dd4558f6337a556ab1850795e0986d11dc2004a908936902f238fef5b81ba297f528d9625d9d37a77b5e962", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af4869a63bc3c2836a9eb049dae5b5d0b9416323f1ac28f937dd07a527870a332ff7744a390e92521f988fb389b8e5d8aabe74b3d8778f06e67ab4973315a8a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc067558d258741c596b44f583c476dab848833863a1f93026c63059158e9291fe3f00d8c3ec9268d0acd1fa5fcb200a878cfcc8820465de0a673f0c44671a71"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 09:14:11.925576) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 09:14:11.926354) ========= 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.t5279XKgYk/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.loej6krHp3/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005109 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 09:14:11.978978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 09:14:11.979306) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 09:14:11.979652) ========= ========= Total master_lag: 0.20 =========