builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0392 starttime: 1483791884.41 results: success (0) buildid: 20170107030205 builduid: 3c86f7ba9ff9425f9b45eaa5440adf79 revision: 0d823cf54df53e0cea75a74adebace956bd333d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.414032) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.414507) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.414795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.442367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.442689) ========= 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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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-01-07 04:24:44-- 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% 73.5M=0s 2017-01-07 04:24:44 (73.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.509432 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.979171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:44.979456) ========= 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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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.024631 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:45.028107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 04:24:45.028411) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0d823cf54df53e0cea75a74adebace956bd333d8 --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 0d823cf54df53e0cea75a74adebace956bd333d8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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-01-07 04:24:45,125 truncating revision to first 12 chars 2017-01-07 04:24:45,125 Setting DEBUG logging. 2017-01-07 04:24:45,125 attempt 1/10 2017-01-07 04:24:45,125 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0d823cf54df5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 04:24:46,318 unpacking tar archive at: mozilla-central-0d823cf54df5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.463442 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 04:24:46.515785) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:46.516104) ========= script_repo_revision: 0d823cf54df53e0cea75a74adebace956bd333d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:46.516937) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:46.517205) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 04:24:46.537108) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-07 04:24:46.537398) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:24:46 INFO - MultiFileLogger online at 20170107 04:24:46 in /builds/slave/test 04:24:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:24:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:24:46 INFO - {'append_to_log': False, 04:24:46 INFO - 'base_work_dir': '/builds/slave/test', 04:24:46 INFO - 'blob_upload_branch': 'Firefox', 04:24:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:24:46 INFO - 'branch': 'Firefox', 04:24:46 INFO - 'buildbot_json_path': 'buildprops.json', 04:24:46 INFO - 'config_files': ('talos/mac_config.py',), 04:24:46 INFO - 'default_actions': ('clobber', 04:24:46 INFO - 'read-buildbot-config', 04:24:46 INFO - 'download-and-extract', 04:24:46 INFO - 'populate-webroot', 04:24:46 INFO - 'create-virtualenv', 04:24:46 INFO - 'install', 04:24:46 INFO - 'run-tests'), 04:24:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:24:46 INFO - 'download_minidump_stackwalk': True, 04:24:46 INFO - 'download_symbols': 'ondemand', 04:24:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:24:46 INFO - 'tooltool.py': '/tools/tooltool.py', 04:24:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:24:46 INFO - '/tools/misc-python/virtualenv.py')}, 04:24:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:24:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:24:46 INFO - 'installer_path': 'installer.exe', 04:24:46 INFO - 'log_level': 'info', 04:24:46 INFO - 'log_name': 'talos', 04:24:46 INFO - 'log_to_console': True, 04:24:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:24:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:24:46 INFO - 'opt_config_files': (), 04:24:46 INFO - 'pip_index': False, 04:24:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:24:46 INFO - 'cmd': ('bash', 04:24:46 INFO - '-c', 04:24:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:24:46 INFO - 'enabled': True, 04:24:46 INFO - 'halt_on_failure': False, 04:24:46 INFO - 'name': 'check_screen_resolution'},), 04:24:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:24:46 INFO - 'cmd': ('bash', 04:24:46 INFO - '-c', 04:24:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:24:46 INFO - 'enabled': True, 04:24:46 INFO - 'halt_on_failure': False, 04:24:46 INFO - 'name': 'check_screen_resolution'},), 04:24:46 INFO - 'run_cmd_checks_enabled': True, 04:24:46 INFO - 'sps_profile': False, 04:24:46 INFO - 'sps_profile_interval': 0, 04:24:46 INFO - 'suite': 'dromaeojs', 04:24:46 INFO - 'system_bits': '32', 04:24:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:24:46 INFO - 'title': 't-yosemite-r7-0392', 04:24:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:24:46 INFO - 'use_talos_json': True, 04:24:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:24:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:24:46 INFO - 'work_dir': 'build'} 04:24:46 INFO - [mozharness: 2017-01-07 12:24:46.735445Z] Running clobber step. 04:24:46 INFO - Running pre-action listener: _resource_record_pre_action 04:24:46 INFO - Running main action method: clobber 04:24:46 INFO - rmtree: /builds/slave/test/build 04:24:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:24:49 INFO - Running post-action listener: _resource_record_post_action 04:24:49 INFO - [mozharness: 2017-01-07 12:24:49.029229Z] Finished clobber step (success) 04:24:49 INFO - [mozharness: 2017-01-07 12:24:49.029354Z] Running read-buildbot-config step. 04:24:49 INFO - Running pre-action listener: _resource_record_pre_action 04:24:49 INFO - Running main action method: read_buildbot_config 04:24:49 INFO - Using buildbot properties: 04:24:49 INFO - { 04:24:49 INFO - "project": "", 04:24:49 INFO - "product": "firefox", 04:24:49 INFO - "script_repo_revision": "production", 04:24:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:24:49 INFO - "repository": "", 04:24:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:24:49 INFO - "buildid": "20170107030205", 04:24:49 INFO - "stage_platform": "macosx64", 04:24:49 INFO - "basedir": "/builds/slave/test", 04:24:49 INFO - "nightly_build": "True", 04:24:49 INFO - "buildnumber": 108, 04:24:49 INFO - "slavename": "t-yosemite-r7-0392", 04:24:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:24:49 INFO - "platform": "yosemite_r7", 04:24:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:24:49 INFO - "branch": "mozilla-central", 04:24:49 INFO - "repo_path": "mozilla-central", 04:24:49 INFO - "revision": "0d823cf54df53e0cea75a74adebace956bd333d8", 04:24:49 INFO - "pgo_build": "False", 04:24:49 INFO - "builduid": "3c86f7ba9ff9425f9b45eaa5440adf79", 04:24:49 INFO - "slavebuilddir": "test" 04:24:49 INFO - } 04:24:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:24:49 INFO - Running post-action listener: _resource_record_post_action 04:24:49 INFO - [mozharness: 2017-01-07 12:24:49.032897Z] Finished read-buildbot-config step (success) 04:24:49 INFO - [mozharness: 2017-01-07 12:24:49.032996Z] Running download-and-extract step. 04:24:49 INFO - Running pre-action listener: _resource_record_pre_action 04:24:49 INFO - Running main action method: download_and_extract 04:24:49 INFO - mkdir: /builds/slave/test/build/tests 04:24:49 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')]} 04:24:49 INFO - trying https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:24:49 INFO - Downloading https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:24:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:24:50 INFO - Downloaded 1199 bytes. 04:24:50 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:24:50 INFO - Using the following test package requirements: 04:24:50 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:24:50 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:24:50 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:24:50 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'jsshell-mac.zip'], 04:24:50 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:24:50 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:24:50 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:24:50 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:24:50 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:24:50 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:24:50 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:24:50 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:24:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:24:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:24:50 INFO - Fetch https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:24:50 INFO - Expected file size: 16300385 04:24:50 INFO - Obtained file size: 16300385 04:24:57 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:24:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:24:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:24:57 INFO - Fetch https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:24:58 INFO - Expected file size: 16300385 04:24:58 INFO - Obtained file size: 16300385 04:25:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:25:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:25:04 INFO - Fetch https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:25:05 INFO - Expected file size: 14403730 04:25:05 INFO - Obtained file size: 14403730 04:25:06 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')]} 04:25:06 INFO - trying https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:25:06 INFO - Downloading https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:25:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:25:08 INFO - Downloaded 69740911 bytes. 04:25:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:25:08 INFO - mkdir: /builds/slave/test/properties 04:25:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:25:08 INFO - Writing to file /builds/slave/test/properties/build_url 04:25:08 INFO - Contents: 04:25:08 INFO - build_url:https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:25:08 INFO - Running post-action listener: _resource_record_post_action 04:25:08 INFO - Running post-action listener: set_extra_try_arguments 04:25:08 INFO - [mozharness: 2017-01-07 12:25:08.737001Z] Finished download-and-extract step (success) 04:25:08 INFO - [mozharness: 2017-01-07 12:25:08.737153Z] Running populate-webroot step. 04:25:08 INFO - Running pre-action listener: _resource_record_pre_action 04:25:08 INFO - Running main action method: populate_webroot 04:25:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:25:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:25:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:25:08 INFO - '--tppagecycles', 04:25:08 INFO - '7'], 04:25:08 INFO - 'tests': ['tsvgm']}}, 04:25:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:25:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:25:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:25:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:25:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['damp', 'tps']}, 04:25:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'tests': ['damp', 'tps']}, 04:25:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['dromaeo_dom']}, 04:25:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:25:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['basic_compositor_video']}, 04:25:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:25:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['a11yr', 04:25:08 INFO - 'ts_paint', 04:25:08 INFO - 'tpaint', 04:25:08 INFO - 'sessionrestore', 04:25:08 INFO - 'sessionrestore_no_auto_restore', 04:25:08 INFO - 'tabpaint']}, 04:25:08 INFO - 'other-e10s': {'tests': ['a11yr', 04:25:08 INFO - 'ts_paint', 04:25:08 INFO - 'tpaint', 04:25:08 INFO - 'sessionrestore', 04:25:08 INFO - 'sessionrestore_no_auto_restore', 04:25:08 INFO - 'tabpaint']}, 04:25:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:25:08 INFO - 'ts_paint', 04:25:08 INFO - 'tpaint', 04:25:08 INFO - 'sessionrestore', 04:25:08 INFO - 'sessionrestore_no_auto_restore', 04:25:08 INFO - 'tabpaint']}, 04:25:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:25:08 INFO - 'ts_paint', 04:25:08 INFO - 'tpaint', 04:25:08 INFO - 'sessionrestore', 04:25:08 INFO - 'sessionrestore_no_auto_restore', 04:25:08 INFO - 'tabpaint']}, 04:25:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['a11yr', 04:25:08 INFO - 'ts_paint', 04:25:08 INFO - 'tpaint', 04:25:08 INFO - 'sessionrestore', 04:25:08 INFO - 'sessionrestore_no_auto_restore', 04:25:08 INFO - 'tabpaint']}, 04:25:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['a11yr', 04:25:08 INFO - 'ts_paint', 04:25:08 INFO - 'tpaint', 04:25:08 INFO - 'sessionrestore', 04:25:08 INFO - 'sessionrestore_no_auto_restore', 04:25:08 INFO - 'tabpaint']}, 04:25:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['tsvgx', 04:25:08 INFO - 'tsvgr_opacity', 04:25:08 INFO - 'tart', 04:25:08 INFO - 'tscrollx', 04:25:08 INFO - 'cart', 04:25:08 INFO - 'tsvg_static']}, 04:25:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:25:08 INFO - 'tsvgr_opacity', 04:25:08 INFO - 'tart', 04:25:08 INFO - 'tscrollx', 04:25:08 INFO - 'cart', 04:25:08 INFO - 'tsvg_static']}, 04:25:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'talos_options': ['--disable-e10s'], 04:25:08 INFO - 'tests': ['tp5o']}, 04:25:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:25:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'talos_options': ['--disable-e10s', 04:25:08 INFO - '--xperf_path', 04:25:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:08 INFO - 'tests': ['tp5n']}, 04:25:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:25:08 INFO - 'talos_options': ['--xperf_path', 04:25:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:08 INFO - 'tests': ['tp5n']}}, 04:25:08 INFO - 'talos.zip': {'path': '', 04:25:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:25:08 INFO - Running post-action listener: _resource_record_post_action 04:25:08 INFO - [mozharness: 2017-01-07 12:25:08.754933Z] Finished populate-webroot step (success) 04:25:08 INFO - [mozharness: 2017-01-07 12:25:08.755067Z] Running create-virtualenv step. 04:25:08 INFO - Running pre-action listener: _resource_record_pre_action 04:25:08 INFO - Running main action method: create_virtualenv 04:25:08 INFO - Creating virtualenv /builds/slave/test/build/venv 04:25:08 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 04:25:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:25:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:25:08 INFO - Using real prefix '/tools/python27' 04:25:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:25:09 INFO - Installing distribute.............................................................................................................................................................................................done. 04:25:12 INFO - Installing pip.................done. 04:25:12 INFO - Return code: 0 04:25:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:25:12 INFO - Reading from file tmpfile_stdout 04:25:12 INFO - Output received: 04:25:12 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) 04:25:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:12 INFO - 'HOME': '/Users/cltbld', 04:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:12 INFO - 'LOGNAME': 'cltbld', 04:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:12 INFO - 'MOZ_NO_REMOTE': '1', 04:25:12 INFO - 'NO_EM_RESTART': '1', 04:25:12 INFO - 'PAGER': '/bin/cat', 04:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:12 INFO - 'PWD': '/builds/slave/test', 04:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:12 INFO - 'SHELL': '/bin/bash', 04:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:12 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:12 INFO - 'USER': 'cltbld', 04:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:12 INFO - 'XPC_FLAGS': '0x0', 04:25:12 INFO - 'XPC_SERVICE_NAME': '0', 04:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:13 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 04:25:13 INFO - Cleaning up... 04:25:13 INFO - Return code: 0 04:25:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:25:13 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')]} 04:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:13 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')]} 04:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:13 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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:13 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 04:25:13 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 04:25:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:13 INFO - 'HOME': '/Users/cltbld', 04:25:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:13 INFO - 'LOGNAME': 'cltbld', 04:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:13 INFO - 'MOZ_NO_REMOTE': '1', 04:25:13 INFO - 'NO_EM_RESTART': '1', 04:25:13 INFO - 'PAGER': '/bin/cat', 04:25:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:13 INFO - 'PWD': '/builds/slave/test', 04:25:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:13 INFO - 'SHELL': '/bin/bash', 04:25:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:13 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:13 INFO - 'USER': 'cltbld', 04:25:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:13 INFO - 'XPC_FLAGS': '0x0', 04:25:13 INFO - 'XPC_SERVICE_NAME': '0', 04:25:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:13 INFO - Downloading/unpacking psutil>=3.1.1 04:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:17 INFO - Installing collected packages: psutil 04:25:17 INFO - Running setup.py install for psutil 04:25:17 INFO - building 'psutil._psutil_osx' extension 04:25:17 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 04:25:18 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 04:25:18 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 04:25:18 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 04:25:18 INFO - building 'psutil._psutil_posix' extension 04:25:18 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 04:25:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:25:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:25:18 INFO - ^ 04:25:18 INFO - 1 warning generated. 04:25:18 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 04:25:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:18 INFO - Successfully installed psutil 04:25:18 INFO - Cleaning up... 04:25:18 INFO - Return code: 0 04:25:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:25:18 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')]} 04:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 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')]} 04:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:18 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 04:25:18 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 04:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:18 INFO - 'HOME': '/Users/cltbld', 04:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:18 INFO - 'LOGNAME': 'cltbld', 04:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:18 INFO - 'MOZ_NO_REMOTE': '1', 04:25:18 INFO - 'NO_EM_RESTART': '1', 04:25:18 INFO - 'PAGER': '/bin/cat', 04:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:18 INFO - 'PWD': '/builds/slave/test', 04:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:18 INFO - 'SHELL': '/bin/bash', 04:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:18 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:18 INFO - 'USER': 'cltbld', 04:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:18 INFO - 'XPC_FLAGS': '0x0', 04:25:18 INFO - 'XPC_SERVICE_NAME': '0', 04:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:25:18 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 04:25:18 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 04:25:18 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 04:25:18 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 04:25:18 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 04:25:18 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 04:25:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:25:21 INFO - Installing collected packages: mozsystemmonitor 04:25:21 INFO - Running setup.py install for mozsystemmonitor 04:25:21 INFO - Successfully installed mozsystemmonitor 04:25:21 INFO - Cleaning up... 04:25:21 INFO - Return code: 0 04:25:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:21 INFO - 'HOME': '/Users/cltbld', 04:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:21 INFO - 'LOGNAME': 'cltbld', 04:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:21 INFO - 'MOZ_NO_REMOTE': '1', 04:25:21 INFO - 'NO_EM_RESTART': '1', 04:25:21 INFO - 'PAGER': '/bin/cat', 04:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:21 INFO - 'PWD': '/builds/slave/test', 04:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:21 INFO - 'SHELL': '/bin/bash', 04:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:21 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:21 INFO - 'USER': 'cltbld', 04:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:21 INFO - 'XPC_FLAGS': '0x0', 04:25:21 INFO - 'XPC_SERVICE_NAME': '0', 04:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:22 INFO - Downloading/unpacking jsonschema==2.5.1 04:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:25:24 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 04:25:24 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 04:25:24 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 04:25:24 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 04:25:24 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 04:25:24 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 04:25:25 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:25 INFO - warning: no files found matching '*.txt' 04:25:25 INFO - no previously-included directories found matching 'build' 04:25:25 INFO - no previously-included directories found matching 'dist' 04:25:25 INFO - no previously-included directories found matching '.git*' 04:25:25 INFO - Installing collected packages: jsonschema, functools32 04:25:25 INFO - Running setup.py install for functools32 04:25:25 INFO - warning: no files found matching '*.txt' 04:25:25 INFO - no previously-included directories found matching 'build' 04:25:25 INFO - no previously-included directories found matching 'dist' 04:25:25 INFO - no previously-included directories found matching '.git*' 04:25:25 INFO - Successfully installed jsonschema functools32 04:25:25 INFO - Cleaning up... 04:25:25 INFO - Return code: 0 04:25:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:25 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 04:25:25 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 04:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:25 INFO - 'HOME': '/Users/cltbld', 04:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:25 INFO - 'LOGNAME': 'cltbld', 04:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:25 INFO - 'MOZ_NO_REMOTE': '1', 04:25:25 INFO - 'NO_EM_RESTART': '1', 04:25:25 INFO - 'PAGER': '/bin/cat', 04:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:25 INFO - 'PWD': '/builds/slave/test', 04:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:25 INFO - 'SHELL': '/bin/bash', 04:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:25 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:25 INFO - 'USER': 'cltbld', 04:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:25 INFO - 'XPC_FLAGS': '0x0', 04:25:25 INFO - 'XPC_SERVICE_NAME': '0', 04:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:25:25 INFO - Cleaning up... 04:25:25 INFO - Return code: 0 04:25:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:25 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 04:25:25 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 04:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:25 INFO - 'HOME': '/Users/cltbld', 04:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:25 INFO - 'LOGNAME': 'cltbld', 04:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:25 INFO - 'MOZ_NO_REMOTE': '1', 04:25:25 INFO - 'NO_EM_RESTART': '1', 04:25:25 INFO - 'PAGER': '/bin/cat', 04:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:25 INFO - 'PWD': '/builds/slave/test', 04:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:25 INFO - 'SHELL': '/bin/bash', 04:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:25 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:25 INFO - 'USER': 'cltbld', 04:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:25 INFO - 'XPC_FLAGS': '0x0', 04:25:25 INFO - 'XPC_SERVICE_NAME': '0', 04:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:26 INFO - Downloading/unpacking blobuploader==1.2.4 04:25:26 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 04:25:26 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 04:25:26 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 04:25:26 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 04:25:26 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 04:25:26 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 04:25:28 INFO - Downloading blobuploader-1.2.4.tar.gz 04:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:25:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:30 INFO - Downloading docopt-0.6.1.tar.gz 04:25:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:25:30 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:30 INFO - Running setup.py install for blobuploader 04:25:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:30 INFO - Running setup.py install for requests 04:25:30 INFO - Running setup.py install for docopt 04:25:30 INFO - Successfully installed blobuploader requests docopt 04:25:30 INFO - Cleaning up... 04:25:31 INFO - Return code: 0 04:25:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:31 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')]} 04:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:31 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')]} 04:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:31 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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:31 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 04:25:31 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 04:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:31 INFO - 'HOME': '/Users/cltbld', 04:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:31 INFO - 'LOGNAME': 'cltbld', 04:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:31 INFO - 'MOZ_NO_REMOTE': '1', 04:25:31 INFO - 'NO_EM_RESTART': '1', 04:25:31 INFO - 'PAGER': '/bin/cat', 04:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:31 INFO - 'PWD': '/builds/slave/test', 04:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:31 INFO - 'SHELL': '/bin/bash', 04:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:31 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:31 INFO - 'USER': 'cltbld', 04:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:31 INFO - 'XPC_FLAGS': '0x0', 04:25:31 INFO - 'XPC_SERVICE_NAME': '0', 04:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-raVCt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-vcB7b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-FuarYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-t8nW5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-UihVSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-CUVSMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:31 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-bmJw3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-iqboOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-m5jb3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-igdu4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-dlRqFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-78FbFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-JP8GY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-GP8v4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-LMCse7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-_ruE1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:32 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-9WADPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:33 INFO - Running setup.py install for manifestparser 04:25:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for mozcrash 04:25:33 INFO - Running setup.py install for mozdebug 04:25:33 INFO - Running setup.py install for mozdevice 04:25:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for mozfile 04:25:33 INFO - Running setup.py install for mozhttpd 04:25:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for mozinfo 04:25:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozInstall 04:25:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozleak 04:25:34 INFO - Running setup.py install for mozlog 04:25:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for moznetwork 04:25:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozprocess 04:25:34 INFO - Running setup.py install for mozprofile 04:25:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozrunner 04:25:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:35 INFO - Running setup.py install for mozscreenshot 04:25:35 INFO - Running setup.py install for moztest 04:25:35 INFO - Running setup.py install for mozversion 04:25:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:35 INFO - Cleaning up... 04:25:35 INFO - Return code: 0 04:25:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:35 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 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:35 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 04:25:35 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 04:25:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:35 INFO - 'HOME': '/Users/cltbld', 04:25:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:35 INFO - 'LOGNAME': 'cltbld', 04:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:35 INFO - 'MOZ_NO_REMOTE': '1', 04:25:35 INFO - 'NO_EM_RESTART': '1', 04:25:35 INFO - 'PAGER': '/bin/cat', 04:25:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:35 INFO - 'PWD': '/builds/slave/test', 04:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:35 INFO - 'SHELL': '/bin/bash', 04:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:35 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:35 INFO - 'USER': 'cltbld', 04:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:35 INFO - 'XPC_FLAGS': '0x0', 04:25:35 INFO - 'XPC_SERVICE_NAME': '0', 04:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-tngCly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-GoV14l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-76J9O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-nUafId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-i70Big-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-HcKjYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-IPAVP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-EYR69Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-BeN6uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:36 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-8fYN2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:37 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)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-PhFOrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:37 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)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-IkRmRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-nye7Hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:37 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)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-CJtzHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:37 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)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-8O7qam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:37 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)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-2Y3V5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:37 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)) 04:25:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:37 INFO - Running setup.py (path:/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/pip-1vsxHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:37 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)) 04:25:37 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)) 04:25:37 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)) 04:25:37 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)) 04:25:37 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)) 04:25:37 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)) 04:25:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:40 INFO - Downloading blessings-1.6.tar.gz 04:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:40 INFO - Installing collected packages: blessings 04:25:40 INFO - Running setup.py install for blessings 04:25:40 INFO - Successfully installed blessings 04:25:40 INFO - Cleaning up... 04:25:40 INFO - Return code: 0 04:25:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:41 INFO - Reading from file tmpfile_stdout 04:25:41 INFO - Current package versions: 04:25:41 INFO - blessings == 1.6 04:25:41 INFO - blobuploader == 1.2.4 04:25:41 INFO - docopt == 0.6.1 04:25:41 INFO - functools32 == 3.2.3-2 04:25:41 INFO - jsonschema == 2.5.1 04:25:41 INFO - manifestparser == 1.1 04:25:41 INFO - mozInstall == 1.12 04:25:41 INFO - mozcrash == 1.0 04:25:41 INFO - mozdebug == 0.1 04:25:41 INFO - mozdevice == 0.49 04:25:41 INFO - mozfile == 1.2 04:25:41 INFO - mozhttpd == 0.7 04:25:41 INFO - mozinfo == 0.9 04:25:41 INFO - mozleak == 0.1 04:25:41 INFO - mozlog == 3.4 04:25:41 INFO - moznetwork == 0.27 04:25:41 INFO - mozprocess == 0.23 04:25:41 INFO - mozprofile == 0.28 04:25:41 INFO - mozrunner == 6.13 04:25:41 INFO - mozscreenshot == 0.1 04:25:41 INFO - mozsystemmonitor == 0.3 04:25:41 INFO - moztest == 0.8 04:25:41 INFO - mozversion == 1.4 04:25:41 INFO - psutil == 3.1.1 04:25:41 INFO - requests == 1.2.3 04:25:41 INFO - wsgiref == 0.1.2 04:25:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:41 INFO - 'HOME': '/Users/cltbld', 04:25:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:41 INFO - 'LOGNAME': 'cltbld', 04:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:41 INFO - 'MOZ_NO_REMOTE': '1', 04:25:41 INFO - 'NO_EM_RESTART': '1', 04:25:41 INFO - 'PAGER': '/bin/cat', 04:25:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:41 INFO - 'PWD': '/builds/slave/test', 04:25:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:41 INFO - 'SHELL': '/bin/bash', 04:25:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:41 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:41 INFO - 'USER': 'cltbld', 04:25:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:41 INFO - 'XPC_FLAGS': '0x0', 04:25:41 INFO - 'XPC_SERVICE_NAME': '0', 04:25:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:25:41 INFO - Cleaning up... 04:25:41 INFO - Return code: 0 04:25:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b79dbf680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, '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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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 04:25:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:25:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:41 INFO - 'HOME': '/Users/cltbld', 04:25:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:41 INFO - 'LOGNAME': 'cltbld', 04:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:41 INFO - 'MOZ_NO_REMOTE': '1', 04:25:41 INFO - 'NO_EM_RESTART': '1', 04:25:41 INFO - 'PAGER': '/bin/cat', 04:25:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:41 INFO - 'PWD': '/builds/slave/test', 04:25:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:41 INFO - 'SHELL': '/bin/bash', 04:25:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:25:41 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:25:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:41 INFO - 'USER': 'cltbld', 04:25:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:41 INFO - 'XPC_FLAGS': '0x0', 04:25:41 INFO - 'XPC_SERVICE_NAME': '0', 04:25:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:41 INFO - Cleaning up... 04:25:41 INFO - Return code: 0 04:25:41 INFO - Running post-action listener: _resource_record_post_action 04:25:41 INFO - Running post-action listener: _start_resource_monitoring 04:25:41 INFO - Starting resource monitoring. 04:25:41 INFO - [mozharness: 2017-01-07 12:25:41.678973Z] Finished create-virtualenv step (success) 04:25:41 INFO - [mozharness: 2017-01-07 12:25:41.679902Z] Running install step. 04:25:41 INFO - Running pre-action listener: _resource_record_pre_action 04:25:41 INFO - Running main action method: install 04:25:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:41 INFO - Reading from file tmpfile_stdout 04:25:41 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:42 INFO - Reading from file tmpfile_stdout 04:25:42 INFO - Output received: 04:25:42 INFO - Usage: mozinstall [options] installer 04:25:42 INFO - Options: 04:25:42 INFO - -h, --help show this help message and exit 04:25:42 INFO - -d DEST, --destination=DEST 04:25:42 INFO - Directory to install application into. [default: 04:25:42 INFO - "/builds/slave/test"] 04:25:42 INFO - --app=APP Application being installed. [default: firefox] 04:25:42 INFO - mkdir: /builds/slave/test/build/application 04:25:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:25:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:26:04 INFO - Reading from file tmpfile_stdout 04:26:04 INFO - Output received: 04:26:04 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:26:04 INFO - Running post-action listener: _resource_record_post_action 04:26:04 INFO - [mozharness: 2017-01-07 12:26:04.043828Z] Finished install step (success) 04:26:04 INFO - [mozharness: 2017-01-07 12:26:04.043983Z] Running run-tests step. 04:26:04 INFO - Running pre-action listener: _resource_record_pre_action 04:26:04 INFO - Running main action method: run_tests 04:26:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:26:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:26:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:26:04 INFO - 2017-01-07 04:26:04.058 screenresolution[1660:10352] starting screenresolution argv=screenresolution get 04:26:04 INFO - 2017-01-07 04:26:04.061 screenresolution[1660:10352] Display 0: 1600x1200x32@60 04:26:04 INFO - 2017-01-07 04:26:04.066 screenresolution[1661:10355] starting screenresolution argv=screenresolution list 04:26:04 INFO - Available Modes on Display 0 04:26:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:26:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:26:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:26:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:26:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:26:04 INFO - Intel Iris: 04:26:04 INFO - Chipset Model: Intel Iris 04:26:04 INFO - Type: GPU 04:26:04 INFO - Bus: Built-In 04:26:04 INFO - VRAM (Dynamic, Max): 1536 MB 04:26:04 INFO - Vendor: Intel (0x8086) 04:26:04 INFO - Device ID: 0x0a2e 04:26:04 INFO - Revision ID: 0x0009 04:26:04 INFO - Displays: 04:26:04 INFO - Display: 04:26:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:26:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:26:04 INFO - Display Serial Number: 1600x1200 60 04:26:04 INFO - Main Display: Yes 04:26:04 INFO - Mirror: Off 04:26:04 INFO - Online: Yes 04:26:04 INFO - Rotation: Supported 04:26:04 INFO - Return code: 0 04:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:26:04 INFO - Python 2.7.3 04:26:04 INFO - Return code: 0 04:26:04 INFO - grabbing minidump binary from tooltool 04:26:04 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')]} 04:26:04 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 0x10cae5d30>, '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 0x10ca0cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cadabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:26:04 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 04:26:04 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 04:26:04 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 04:26:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:26:04 INFO - Return code: 0 04:26:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:26:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:26:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:26:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:26:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0392', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0392 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eVEGuHbUxs/Render', 04:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:04 INFO - 'HOME': '/Users/cltbld', 04:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:04 INFO - 'LOGNAME': 'cltbld', 04:26:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:26:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:04 INFO - 'MOZ_NO_REMOTE': '1', 04:26:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:26:04 INFO - 'NO_EM_RESTART': '1', 04:26:04 INFO - 'PAGER': '/bin/cat', 04:26:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:04 INFO - 'PWD': '/builds/slave/test', 04:26:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:04 INFO - 'SHELL': '/bin/bash', 04:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CKEpSmWsXJ/Listeners', 04:26:04 INFO - 'TMPDIR': '/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/', 04:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:04 INFO - 'USER': 'cltbld', 04:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:04 INFO - 'XPC_FLAGS': '0x0', 04:26:04 INFO - 'XPC_SERVICE_NAME': '0', 04:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0392', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:26:04 INFO - mozversion application_buildid: 20170107030205 04:26:04 INFO - mozversion application_changeset: 0d823cf54df53e0cea75a74adebace956bd333d8 04:26:04 INFO - mozversion application_display_name: Nightly 04:26:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:26:04 INFO - mozversion application_name: Firefox 04:26:04 INFO - mozversion application_remotingname: firefox 04:26:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:26:04 INFO - mozversion application_vendor: Mozilla 04:26:04 INFO - mozversion application_version: 53.0a1 04:26:04 INFO - mozversion platform_buildid: 20170107030205 04:26:04 INFO - mozversion platform_changeset: 0d823cf54df53e0cea75a74adebace956bd333d8 04:26:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:26:04 INFO - mozversion platform_version: 53.0a1 04:26:04 INFO - using testdate: 1483791964 04:26:04 INFO - actual date: 1483791964 04:26:04 INFO - starting webserver on 'localhost:49242' 04:26:04 INFO - SUITE-START | Running 2 tests 04:26:04 INFO - TEST-START | dromaeo_css 04:26:04 INFO - operating with platform_type : mac_ 04:26:04 INFO - Initialising browser for dromaeo_css test... 04:26:04 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmpQzspHy/profile http://localhost:49242/getInfo.html) 04:26:05 INFO - PROCESS | 1671 | 1483791965299 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 04:26:07 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 04:26:07 INFO - PROCESS | 1671 | colorDepth:24 04:26:07 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 04:26:07 INFO - PROCESS | 1671 | __metrics 04:26:07 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:26:07 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:26:08 INFO - TEST-INFO | 1671: exit 0 04:26:08 INFO - Browser initialized. 04:26:08 INFO - Running cycle 1/1 for dromaeo_css test... 04:26:08 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmpQzspHy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:20 INFO - PROCESS | 1675 | RSS: Main: 234397696 04:26:20 INFO - PROCESS | 1675 | 04:29:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 04:29:25 INFO - PROCESS | 1675 | RSS: Main: 336621568 04:29:25 INFO - PROCESS | 1675 | 04:31:08 INFO - PROCESS | 1675 | ************************* 04:31:08 INFO - PROCESS | 1675 | A coding exception was thrown and uncaught in a Task. 04:31:08 INFO - PROCESS | 1675 | 04:31:08 INFO - PROCESS | 1675 | Full message: ReferenceError: fetch is not defined 04:31:08 INFO - PROCESS | 1675 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:31:08 INFO - PROCESS | 1675 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:31:08 INFO - PROCESS | 1675 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:31:08 INFO - PROCESS | 1675 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:31:08 INFO - PROCESS | 1675 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:31:08 INFO - PROCESS | 1675 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:31:08 INFO - PROCESS | 1675 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:31:08 INFO - PROCESS | 1675 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:31:08 INFO - PROCESS | 1675 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:31:08 INFO - PROCESS | 1675 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:31:08 INFO - PROCESS | 1675 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:31:08 INFO - PROCESS | 1675 | 04:31:08 INFO - PROCESS | 1675 | ************************* 04:32:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 04:32:29 INFO - PROCESS | 1675 | RSS: Main: 369127424 04:32:29 INFO - PROCESS | 1675 | 04:35:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 04:35:32 INFO - PROCESS | 1675 | RSS: Main: 346308608 04:35:32 INFO - PROCESS | 1675 | 04:38:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 04:38:35 INFO - PROCESS | 1675 | RSS: Main: 352534528 04:38:35 INFO - PROCESS | 1675 | 04:41:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 04:41:39 INFO - PROCESS | 1675 | RSS: Main: 331051008 04:41:39 INFO - PROCESS | 1675 | 04:44:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 04:44:43 INFO - PROCESS | 1675 | RSS: Main: 415981568 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | __start_tp_report 04:44:43 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 04:44:43 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 04:44:43 INFO - PROCESS | 1675 | |i|pagename|runs| 04:44:43 INFO - PROCESS | 1675 | |0;dojo.html;3304.917377065573;3305.140663427509;3326.3846188455136;3345.983270083649;3371.157210697326;5656.462636049569;5672.659640421578;5687.658740475575;5751.223584816049;5761.971190144049;5693.060644993581;5709.514691251248;5800.709964501769;5805.10020946754;5816.767329306823;5139.460356662553;5166.948330516684;5204.895902081956;5225.503577160163;5234.764435600388;5289.365276166865;5292.179712144623;5292.947070529284;5293.285406470135;5339.359276886779;5261.552768014723;5272.367315922113;5287.894242115155;5297.682139071649;5299.417064122943;5156.767945442464;5173.22401639757;5191.506806853381;5191.766370513336;5206.24509446132;5197.766100525486;5224.268602395668;5248.685078895279;5271.367435907678;5284.550813180885;4727.409073865767;5702.971485142548;5706.315242170925;5708.65748055118;5718.885622287573;6137.386261373871;6144.3103146634585;6152.538559608047;6290.748370065192;6310.5267104967315;6223.657698826562;6250.499960003189;6287.245530536319;6341.524385671093;6356.809295721136;5815.244018277597;5848.181254624356;5874.471297583238;5881.794137205177;5907.556933229982;6250.937490625036;6297.370262973666;6301.684915754286;6304.936950630435;6315.273743519462;6047.7278522466595;6102.481289090389;6112.480439162721;6115.575014303891;6168.660723660242;6114.266954506099;6295.087212354183;6305.211848518935;6306.180196574416;6317.905231421532;6105.763021687015;6117.675163990783;6127.080937859268;6148.8770224595255;6210.782622608187;6304.022876454157;6308.116863639094;6313;6316.242050953915;6317;4903.8528844134735;4954.281629163823;4962.478939711278;5009.098362294749;5036.622253330942;4427.933580996288;4459.732416055047;4486.057927835126;4489.865304040884;4489.910201795946;5702.54379649627;5715.685637289906;5753.453421924911;5763.711814409263;5819.127130930309;6301.338359472281;6304.338044505261;6306.936930630635;6315.242170939425;6319.241690997018;5717.828465146053;5751.4536119068625;5758.654480731169;5763.769449221984;5767.653940763567;5285.101532739443;5326.733663316896;5328.973355133199;5425.701586412706;5444.101723215705;6233.2208473940755;6258.217722784652;6276.686165691697;6338.366163383625;6339.302676705651;6186.443220110212;6257.405546473078;6282.811515654537;6301.96849015752;6312.621242725451;6261.592996455216;6311.621302721851;6318.399752023551;6335.303116657256;6337.556371053982;6105.15650299495;6111.327753947151;6134.712129681229;6165.229346331708;6239.407256310643;5249.973750131225;5412.188171774108;6306.211723534559;6326.873462530815;6340.714667839866;6094;6153.630782153085;6165.167702360124;6166.136740856194;6173.753049877954;5020.071286811952;5986.9401305986385;6024.849378765391;6025.517958563392;6119.84700382494;5945.524358051432;5948.226730525177;5978.312494063064;5984.910226346696;6064.332923378337;5971.373005834499;6038.215032045806;6054.727537260922;6129.2032035835055;6154.1999540059505;5924.7926322578505;5930.584859059997;5974.253218347707;5997.0404735242155;6010.398960103955 04:44:43 INFO - PROCESS | 1675 | |1;ext.html;492.08717828428263;513.5711680746576;513.8438513344972;548.8545535433113;550.4522999615383;38158;39931;40123.99690007751;41973.7901310493;42262.577374226246;40591.79704101483;40689;40697;40703;40758.388624170664;8448.23965843077;9184.081591840813;9373.250139988819;9404.106609872086;9418.058194180596;8526.658933642679;11073.390963497004;11315.660530184108;11320.490577924013;11475.655730328104;11171.441427928612;11213.551457941672;11213.551457941714;11220.719482012973;11226.607068752595;8407.159284071606;8623.827523449496;9547.2839537035;11234.49444774983;11318.490667919963;8562.48625082497;8624.870626940601;8626.482411055356;10788.56845726178;11133.332000080021;38765.41851872224;39094.80452597747;39110.41334379987;39195.412068818994;39324;3493.493443450711;3917.138229589486;3921.117748506592;3932.646061854447;3983.960160398388;28859.134225973256;29323.560146597818;29597;29668.851655741582;29951.400971980438;3175.952360714591;3181.236503239206;3188.2826364068133;3253.8535765890592;3285.2608163163336;347776.8334749782;348917;350800;353468;354080.2295988555;54959;55394.44605553974;55419;55423;55470.722646387316;58388;58523.70738146282;58590.82818343609;58624.41375586275;58670;13919.65200869966;14800.703985920221;15415.229238538253;15441.845581544265;15450.84549154494;10895.237333386587;11132.10943124548;11150;12026.218295810744;12125.757484850254;14679.55961321162;14877.925610371878;15051.774223386657;15223.61940951463;15248.847511524964;5251.947480525146;6191.380861913773;6214.471769899519;6269.467095296951;6298.700974144404;865.6191275838611;869.1959937058195;873.2010210657304;879.7052987249215;906.6282824042111;28818.85590572034;29077.563836542467;29216.853915730284;29523.704762952526;29588.55617165744;937.901520340368;980.3872579637726;981.1807141037172;1001.1190152665607;1060.6605886116522;12523.747625237585;13028.348582571023;14930.402783888523;14972.550823475312;15356.692866142615;557008;562300;562992;564980;565144.174279126;56005.7199714007;56590;56668.14997775036;56693;56787;1345.8384993800807;1399.8320201575875;1405.859414058586;1411.364885801373;1415.3206460898825;24158.75841241565;27805.470699111742;28281.15156545307;28466;29221;43333.91665208395;43746.781266093465;46929.53070469388;47057;47083.529164707914;481146;491325;493460;496095;498172;31821.840890795396;32694.34611307808;36687.26625467529;36691;37055.814720926224;42848;43880.56119438765;43952.78023609862;44844.55155448404;45340;31297.687023129478;37150.88547343584;37252.627473726;37448.06379840417;38564.6143538561;36676;36726.44910326401;37024.81487592545;37671.24657506889;38211.80894095623;31140.065798026095;35352.469712954844;35700.821495892356;36171.457428139125;36424.90725278246;31568.842155789072;32456.83771581127;32794.67205328012;32838.507422389164;32924.01227963165;19911.701324479855;21789.67315490237;23279.3016209514;23973.160939367037;24531.38671533226 04:44:43 INFO - PROCESS | 1675 | |2;jquery.html;382.9770213787171;388.33594553313833;392.3095352180162;395.42860566481437;417.79528031264687;12805;15644.530664080095;19521.51196220095;20770.753754774705;20817.06323215457;19580.608387832235;20097.899510502426;20150.49623759403;20173.394798156078;20300.086496107673;5510.8071217507395;5712.857178570548;5764.135379693059;5845.006348920694;5846.795362162326;4810.639202059842;5718.17086522454;5822.9417705822825;5824.825255242328;5826.621269617462;5712.600117991742;5728.570357223225;5749.798757043504;5772.5670574707065;5785.681787501686;5509.47659972302;5664.065429204176;5741.339745929209;5750.798722044729;5758.856028599298;5017.824376146854;5040.722760248185;5045.84862454127;5051.040302342543;5056.241563765428;20138.69791953122;22825.315240542644;24249.030038798428;24970.625440618405;25227.747722522723;2556.463142740027;2568.3707491664477;2604.791616670662;2614.9869250653815;2689.744474274941;10120.696379108565;10341.327813692085;10697.411642359668;10763.19276054299;15534.456294029655;1832.660957722812;1845.335679155503;1866.346778627483;1874.8875067495994;1879.5113270549755;127746.47676087008;134256.65743342636;137897.31051344678;140580;142704.58363330908;11477.36874471912;11549.595764148215;11645.6506304811;11665.766684666429;11915.52337906474;13134.540291089766;14892.180930048951;14914.776278355834;14917.328720207617;14920.253987300592;7591.8861217081785;7794.688212471551;10462.058414742709;10938.781224375469;10981.560737570568;6717.899231511531;7404.518706284074;8278.544680042656;8700.651973920973;8716.607752651145;8592.742217733477;9176.852893388326;9692.321537492307;9828;9951.502424878727;3958.4853968984426;4002.5397079335667;4043.029672878547;4063.216696562864;4070.9796451017555;896.8968568614563;903.1284810158229;943.2783920300839;967.9661211857692;1011.9342242754344;15295.770563441558;18553.721694174597;19663.901680491505;20122.819553192683;21166.153353865982;850.2517784349733;1001.4592120254981;1026.9332493387906;1067.4555976452066;1073.6886302972207;9908.504574771232;10626.096781773482;10644.604963883065;10650.627228046982;10694.572217111228;642231.7888410528;661483;665871;668402;673673;107166.92833071726;130078;136506;149552;154786;1007.1187710753092;1013.9949300253452;1032.607609108534;1035.8394448860438;1037.0459177556515;17817.73273400874;18245;18280.360187393377;18372.448826535227;18440.631187375908;58818.41181588129;58858.41141588529;59084;59459.70270148795;60067.39932600793;84187.5790621043;86287.13712862792;86390;86461.70307445519;86488.5675571618;6777.593344399154;7002.614856182959;7928.960355198187;7933.920660793549;8132.512049277062;9729.659461918798;11402.144839137263;12224.083193760682;12598.118131731058;12621.558245461365;9036.186743193144;10225.897741022793;10749.140068794633;10915.399653018843;10921.617743378943;8229.629666664894;10469.319494233156;10681.412522311348;10841.457927103771;10924.890751092387;8936.955315223642;10623.89376106229;10996.450177491253;11002.50488727993;11018.944905275743;7854;8005.039395272604;8028;8208.302294305051;8376.53929033909;9821.85267220978;10224.744381390525;10529.894701053197;10583.4708264588;10602.416867072077 04:44:43 INFO - PROCESS | 1675 | |3;mootools.html;1397.5667543061645;1411.632975426389;1424.2166808255456;1424.914505129693;1432.3697573067845;8000;8439.409241353107;8445.535495547743;8456.450330728507;8479.024912135106;6699.899501507482;7415;7611.581363025031;7612.885806712904;8256.133106023855;2303.078768492608;2336.6261398176293;2354.9764502355015;2358.8938497767554;2362.078789272185;2322.7677232276724;2365.1485465232504;2368.159303447277;2375.9643605345846;2377.8454400464;2055.68136938774;2130.7975742304457;2324.9651255231183;2349.8120150388013;2351.6013666060608;2254.9323520294415;2329.219711396676;2329.6389059695775;2338.918137865184;2339.5320935812906;2320.640300753386;2341.8477798943013;2343.484433424644;2349.4126468382906;2351.012574718622;8187.631556579968;8332.958335208346;8342.499450032958;8362.540060296682;8438.831223375559;5042.445331013622;5157.53582177602;5216.8695782605355;5218.817341393071;5230.97384513075;3264.2165880188822;3460.913477163066;3493.493443450711;3500.6674365935196;3538.4161613333767;1185.2884782987667;1208.2689972566645;1247.812828075796;1260.3005332040714;1265.1270623269913;10642.57429702811;10920.890791092146;10930.344179349186;10930.726731831688;10942.288751231144;4511.255642818898;4636.095961287584;4673.976630116827;4725.598324142417;4769.093872164278;8733.820934173806;8784.341174411944;8788.78028049291;8792.692255771017;8801.163889430494;4494.617957473652;4990;5004.224345226496;5135.820246291362;5149.76826042829;4624.676272660881;4743.525647435229;4805.37530121089;4816.710997340171;4817.373741413594;3564.180238545149;3628.292482965849;3651.634836516327;3687.1519550503;3701.3892707703467;1143.348291473852;1152.6426807689643;1159.652104368686;1167.241293159453;1169.2633640806407;756.5082696247373;866.2160744526213;881.4843316659826;883.8983516895511;896.4800415758845;4063.878083657495;5338.973305133527;5344;5368.32895888014;5473.67157970523;481.86748644123145;579.1949190625035;589.028103629007;920.4707293306376;922.2483675804198;3561.554805649294;3684.649958254015;3786.962130378716;3793.487879136281;3804.144067584771;7363.7422690205585;9719.708408747749;9919.702408927744;9949.502524873873;9991.900080999098;9757.512124393752;9817.214622830157;9919.454430006419;9935.602575897028;9938.45338506389;7464.402847772165;7675.53946763196;7703.1911649277135;7781.766547003599;7965.800854978556;4593.96635756952;5510.448955104457;5545.584081193846;5673.092305231144;5891.528677705687;9197;9255.305852061258;9292.95353523228;9293.30300227472;9301.581428835854;8411.83176336482;8976.326775491994;9386.953065234904;9404.200642945152;9433.95283023608;6610.537262391586;6837.589744615339;6838.589684618939;6840.213375461885;6840.931590684029;7233.710651573878;7446.7021319146625;7533.171351151259;7614;7671.923280767121;7068.151821781419;7107.182673992557;7179.138503379628;7216.3505284524645;7234.782956511313;6929;6965.303469652994;7038.859222815618;7076.080109585927;7133.714651414093;6278.623282603058;6399.424051835357;6399.55203135776;6409.871802563829;6425.9036114457385;3890.7276490646514;4855.077535268288;5008.524190201925;5026.924596131134;5072.416672082758;4858.708477491362;4910.582600479;5022.623303252198;5123.923141152811;5155.123628983007 04:44:43 INFO - PROCESS | 1675 | |4;prototype.html;314.9921251968702;341.5832684125366;351.4412084785192;362.86936702787;369.51593412629455;4460.776961151938;5006.77334053157;5138.306328645632;5227.4511176326505;5386.596005299603;4836.467988521269;5261.684298942067;5268.4994925482115;5277.234800953859;5289.761960711768;3437.896863094105;3840.577536470986;4152.6055024772595;4177.352510360899;4201.663866890642;3400.4899265110307;3613.2900324756433;3999.880003599882;4015.9598404016024;4140.047789008529;4049.9797501012454;4055.1078762672164;4063.5168163620215;4066.8373265069363;4087.2438598859308;4058.3100872851537;4083.3874918762276;4090.672746180328;4101.343784994387;4121.237571049365;2887.754540864029;3405.872262336936;3474.3572439098593;3577.123604716835;3613.295407395559;4937.901241975141;5016.84612539118;5029.723365214912;5034.446210916833;5085.237214417867;4081.7346872453195;4507.459104907432;4664.72011679297;4691.507391723888;4707.882302942419;5153.66501177426;5345.541051116491;5530.953547187566;5591.384947655754;5646.971765141148;3994.2410941920944;3999.8800035998966;4015.1768887378016;4022.617851304151;4022.738521996061;4945.604351651859;5367.5705943524035;5424.593155513352;5812.534997200214;5843.561732870052;3394.932101357959;3428.4857271409487;3943.093088589583;3984.023914141054;4092.8362865485046;4110.917781644351;4138.5654506276405;4144.295469770146;4150.584941505826;4160.271952408317;4695.24876019842;4706.788194531255;4779.498152693917;4783.1868582341085;4787.760611969457;5165.870853228625;5599.480233894764;5615.1643420072605;5649.661020338793;5729.1119876419225;1257.6415721519322;1257.8176514076922;1259.3766085787595;1265.6266401411422;1273.7008251583334;725.8403151306704;855.2388374346837;859.9957000214958;872.5637181409295;874.9107361335178;4589.908201835945;4631.564215093332;4872.561469467765;4905.337779399735;4930.8767280818265;4898.779554920037;5376.731163441812;5413.945860541344;5466.562674985991;5566.860828479239;4543.45478542577;4850.587700045465;4936.802527898843;5077.918053598007;5099.4390617032095;2294.38051726033;2394.712634483873;2733.58996150575;2811.9578206326923;2844.900428484993;5512.944870551323;5581.05122129239;5600.6919619420505;5621.8594535136945;5646.378898321182;4924.655274130777;5169.638125331266;5216.60875434344;5515.310586176728;5521.944780552143;4783.56947874686;5045.940352525938;5575.275214222206;5656.406077361982;5722.713864306851;4521.751303678329;4736.147493451212;4910.459849416633;5105.412877518985;5471.398146203994;4298.63461605767;4643.558861908114;5373.662456831291;5375.650582712111;5377.408485066561;4260.826978241057;4477.366255144033;5207.3230480037355;5540.833774986757;5609.102543593006;3422.589289285202;3581.534400528019;3616.782993020322;3631.237440137495;3637.090327737852;5125.564327032244;5404.189371594292;5452.182172674131;5470.671759694431;5499.532539734168;4890.33980412654;5127.333446651912;5176.844694659167;5296.973515132399;5327.813526526553;5263.36839579253;5263.605229607718;5271.393789714232;5305.204219367126;5317.734113294242;4500.099980004078;4923.153817318505;5066.873328166826;5129.307543481582;5173.7494190027455;4671.883202919818;5099.730619874121;5220.1647736362;5323.920141197963;5330.9466905332;4499.910001800011;4973.428055773632;5217.660852044605;5261.131913234273;5265.262863199232 04:44:43 INFO - PROCESS | 1675 | |5;yui.html;687.9587224766517;694.291822341212;748.6069813347991;797.42186914487;827.569663774837;14831.332590033448;15044.623884402896;15513.767293490606;15803.525894223163;15812.288447019882;13925.930370348135;14171.574852754435;14387.280635968185;14400.20798856065;14400.783988240159;849.8427790858696;878.2666473494639;881.3478026260585;881.5636260051284;883.2889523933231;675.2909445082668;693.098971337262;699.433458898294;837.0624900111883;870.5951732444412;926.7683079230193;927.2303987690235;928.3269629518579;928.9688445825128;933.8505839065751;927.1099744245498;927.452802846324;927.6984979881498;928.8792456980583;928.9024652411467;516.888868893186;533.0352062766406;665.487574567585;689.5724650716538;691.8270432391902;14559.927200364036;15101.546953591409;15103.622409439742;15149.394024239018;15150.242487875563;11639.6508104757;11837.822432663517;13724.93137534306;13925.871178450563;14080.71838563223;12542.81185782214;12678.492860285578;12701.682457938534;12809.487620495169;13248.536301229411;4297.634701050383;4313.525512193688;4424.115176964588;4507.774611269423;4509.1658043262105;109364.90635093627;109963.45018274858;110347.44826275898;113279.43360283146;113592.86407135989;10176.389416635026;10586.57653693859;10617.522211500502;10793.946030269955;11268.380239086766;17456.214470348867;20761.688574671392;21054.05256763449;21187.94060296979;21264.468388290417;7295.306945840137;7403.407727381797;7499.66251518672;7570.583617900958;7601.277878601524;4227.112306415625;4337.7397356158735;4341.240282950471;4341.934870976938;4343.153085148366;3469.5836499619704;3517.680869673872;3529.964700353015;3563.964360356415;3572.9464058039152;590.8995470769979;597.375742349246;602.3885755957706;606.6481440764346;614.3764079459353;546.8461546137646;597.3280059932575;630.4294613374904;659.9118461133233;673.0678010954863;9170.724878253663;9369.203617692436;9410.576524056434;9715.36850104741;9964.80070398588;627.645380360095;638.3488841381765;652.2233988855792;658.7727234104226;672.4485921544286;3936.651358572584;4655.557722016403;4703.717776933395;4704.341392205094;4745.905081898342;80346.3930721394;95893;99130.5043474778;101308.49345753371;103083.96916030743;95951.0404895956;96516;97169.5424568632;97447;97573.53639695268;78729.21270787218;78944.21055789484;83959;83975.580122099;84837.57581212054;8550.888384509974;9163.083691630784;9168.679096231599;9183;9238.538073096453;44182.33726494169;44477.777611111735;52460;53833;54628.18057729058;62813.68593157005;63045;69185.65407172932;72028.63985680247;72095.63952180206;12356.320402377596;12777.680557986125;12901.935490322488;12933.224006559636;13224.867751322749;14353.569392918229;14522.056066355653;14640.26798660084;14815.037022593497;15105.32026058852;13597.592072237849;13662.11196272239;13736.65658358549;13769.31153442304;13944.721105578035;13170;13543.79684304756;13569.932150339186;13693.931530342285;13734.725305494034;13091.345432728516;13235.735285294433;13311.600651980456;13568.253746044062;13899.652508687364;10395.532201050819;11013.83479247828;11078.003143329008;11428;11631.709207269885;10100.242481813746;10507.31702439339;10618.097461715532;11316.660500184678;11628.360440175533 04:44:43 INFO - PROCESS | 1675 | __end_tp_report 04:44:43 INFO - PROCESS | 1675 | __start_cc_report 04:44:43 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2225 04:44:43 INFO - PROCESS | 1675 | __end_cc_report 04:44:43 INFO - PROCESS | 1675 | __startTimestamp1483793083156__endTimestamp 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | ------- Summary: start ------- 04:44:43 INFO - PROCESS | 1675 | Number of tests: 6 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5745.06 Median:5971.37 stddev:645.06 (10.8%) stddev-sans-first:617.98 04:44:43 INFO - PROCESS | 1675 | Values: 3304.9 3305.1 3326.4 3346.0 3371.2 5656.5 5672.7 5687.7 5751.2 5762.0 5693.1 5709.5 5800.7 5805.1 5816.8 5139.5 5166.9 5204.9 5225.5 5234.8 5289.4 5292.2 5292.9 5293.3 5339.4 5261.6 5272.4 5287.9 5297.7 5299.4 5156.8 5173.2 5191.5 5191.8 5206.2 5197.8 5224.3 5248.7 5271.4 5284.6 4727.4 5703.0 5706.3 5708.7 5718.9 6137.4 6144.3 6152.5 6290.7 6310.5 6223.7 6250.5 6287.2 6341.5 6356.8 5815.2 5848.2 5874.5 5881.8 5907.6 6250.9 6297.4 6301.7 6304.9 6315.3 6047.7 6102.5 6112.5 6115.6 6168.7 6114.3 6295.1 6305.2 6306.2 6317.9 6105.8 6117.7 6127.1 6148.9 6210.8 6304.0 6308.1 6313.0 6316.2 6317.0 4903.9 4954.3 4962.5 5009.1 5036.6 4427.9 4459.7 4486.1 4489.9 4489.9 5702.5 5715.7 5753.5 5763.7 5819.1 6301.3 6304.3 6306.9 6315.2 6319.2 5717.8 5751.5 5758.7 5763.8 5767.7 5285.1 5326.7 5329.0 5425.7 5444.1 6233.2 6258.2 6276.7 6338.4 6339.3 6186.4 6257.4 6282.8 6302.0 6312.6 6261.6 6311.6 6318.4 6335.3 6337.6 6105.2 6111.3 6134.7 6165.2 6239.4 5250.0 5412.2 6306.2 6326.9 6340.7 6094.0 6153.6 6165.2 6166.1 6173.8 5020.1 5986.9 6024.8 6025.5 6119.8 5945.5 5948.2 5978.3 5984.9 6064.3 5971.4 6038.2 6054.7 6129.2 6154.2 5924.8 5930.6 5974.3 5997.0 6010.4 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:60949.29 Median:37201.76 stddev:126972.77 (341.3%) stddev-sans-first:127247.82 04:44:43 INFO - PROCESS | 1675 | Values: 492.1 513.6 513.8 548.9 550.5 38158.0 39931.0 40124.0 41973.8 42262.6 40591.8 40689.0 40697.0 40703.0 40758.4 8448.2 9184.1 9373.3 9404.1 9418.1 8526.7 11073.4 11315.7 11320.5 11475.7 11171.4 11213.6 11213.6 11220.7 11226.6 8407.2 8623.8 9547.3 11234.5 11318.5 8562.5 8624.9 8626.5 10788.6 11133.3 38765.4 39094.8 39110.4 39195.4 39324.0 3493.5 3917.1 3921.1 3932.6 3984.0 28859.1 29323.6 29597.0 29668.9 29951.4 3176.0 3181.2 3188.3 3253.9 3285.3 347776.8 348917.0 350800.0 353468.0 354080.2 54959.0 55394.4 55419.0 55423.0 55470.7 58388.0 58523.7 58590.8 58624.4 58670.0 13919.7 14800.7 15415.2 15441.8 15450.8 10895.2 11132.1 11150.0 12026.2 12125.8 14679.6 14877.9 15051.8 15223.6 15248.8 5251.9 6191.4 6214.5 6269.5 6298.7 865.6 869.2 873.2 879.7 906.6 28818.9 29077.6 29216.9 29523.7 29588.6 937.9 980.4 981.2 1001.1 1060.7 12523.7 13028.3 14930.4 14972.6 15356.7 557008.0 562300.0 562992.0 564980.0 565144.2 56005.7 56590.0 56668.1 56693.0 56787.0 1345.8 1399.8 1405.9 1411.4 1415.3 24158.8 27805.5 28281.2 28466.0 29221.0 43333.9 43746.8 46929.5 47057.0 47083.5 481146.0 491325.0 493460.0 496095.0 498172.0 31821.8 32694.3 36687.3 36691.0 37055.8 42848.0 43880.6 43952.8 44844.6 45340.0 31297.7 37150.9 37252.6 37448.1 38564.6 36676.0 36726.4 37024.8 37671.2 38211.8 31140.1 35352.5 35700.8 36171.5 36424.9 31568.8 32456.8 32794.7 32838.5 32924.0 19911.7 21789.7 23279.3 23973.2 24531.4 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:37836.98 Median:25099.19 stddev:110766.54 (441.3%) stddev-sans-first:111041.57 04:44:43 INFO - PROCESS | 1675 | Values: 383.0 388.3 392.3 395.4 417.8 12805.0 15644.5 19521.5 20770.8 20817.1 19580.6 20097.9 20150.5 20173.4 20300.1 5510.8 5712.9 5764.1 5845.0 5846.8 4810.6 5718.2 5822.9 5824.8 5826.6 5712.6 5728.6 5749.8 5772.6 5785.7 5509.5 5664.1 5741.3 5750.8 5758.9 5017.8 5040.7 5045.8 5051.0 5056.2 20138.7 22825.3 24249.0 24970.6 25227.7 2556.5 2568.4 2604.8 2615.0 2689.7 10120.7 10341.3 10697.4 10763.2 15534.5 1832.7 1845.3 1866.3 1874.9 1879.5 127746.5 134256.7 137897.3 140580.0 142704.6 11477.4 11549.6 11645.7 11665.8 11915.5 13134.5 14892.2 14914.8 14917.3 14920.3 7591.9 7794.7 10462.1 10938.8 10981.6 6717.9 7404.5 8278.5 8700.7 8716.6 8592.7 9176.9 9692.3 9828.0 9951.5 3958.5 4002.5 4043.0 4063.2 4071.0 896.9 903.1 943.3 968.0 1011.9 15295.8 18553.7 19663.9 20122.8 21166.2 850.3 1001.5 1026.9 1067.5 1073.7 9908.5 10626.1 10644.6 10650.6 10694.6 642231.8 661483.0 665871.0 668402.0 673673.0 107166.9 130078.0 136506.0 149552.0 154786.0 1007.1 1014.0 1032.6 1035.8 1037.0 17817.7 18245.0 18280.4 18372.4 18440.6 58818.4 58858.4 59084.0 59459.7 60067.4 84187.6 86287.1 86390.0 86461.7 86488.6 6777.6 7002.6 7929.0 7933.9 8132.5 9729.7 11402.1 12224.1 12598.1 12621.6 9036.2 10225.9 10749.1 10915.4 10921.6 8229.6 10469.3 10681.4 10841.5 10924.9 8937.0 10623.9 10996.5 11002.5 11018.9 7854.0 8005.0 8028.0 8208.3 8376.5 9821.9 10224.7 10529.9 10583.5 10602.4 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5327.85 Median:5217.84 stddev:2902.12 (55.6%) stddev-sans-first:2895.23 04:44:43 INFO - PROCESS | 1675 | Values: 1397.6 1411.6 1424.2 1424.9 1432.4 8000.0 8439.4 8445.5 8456.5 8479.0 6699.9 7415.0 7611.6 7612.9 8256.1 2303.1 2336.6 2355.0 2358.9 2362.1 2322.8 2365.1 2368.2 2376.0 2377.8 2055.7 2130.8 2325.0 2349.8 2351.6 2254.9 2329.2 2329.6 2338.9 2339.5 2320.6 2341.8 2343.5 2349.4 2351.0 8187.6 8333.0 8342.5 8362.5 8438.8 5042.4 5157.5 5216.9 5218.8 5231.0 3264.2 3460.9 3493.5 3500.7 3538.4 1185.3 1208.3 1247.8 1260.3 1265.1 10642.6 10920.9 10930.3 10930.7 10942.3 4511.3 4636.1 4674.0 4725.6 4769.1 8733.8 8784.3 8788.8 8792.7 8801.2 4494.6 4990.0 5004.2 5135.8 5149.8 4624.7 4743.5 4805.4 4816.7 4817.4 3564.2 3628.3 3651.6 3687.2 3701.4 1143.3 1152.6 1159.7 1167.2 1169.3 756.5 866.2 881.5 883.9 896.5 4063.9 5339.0 5344.0 5368.3 5473.7 481.9 579.2 589.0 920.5 922.2 3561.6 3684.6 3787.0 3793.5 3804.1 7363.7 9719.7 9919.7 9949.5 9991.9 9757.5 9817.2 9919.5 9935.6 9938.5 7464.4 7675.5 7703.2 7781.8 7965.8 4594.0 5510.4 5545.6 5673.1 5891.5 9197.0 9255.3 9293.0 9293.3 9301.6 8411.8 8976.3 9387.0 9404.2 9434.0 6610.5 6837.6 6838.6 6840.2 6840.9 7233.7 7446.7 7533.2 7614.0 7671.9 7068.2 7107.2 7179.1 7216.4 7234.8 6929.0 6965.3 7038.9 7076.1 7133.7 6278.6 6399.4 6399.6 6409.9 6425.9 3890.7 4855.1 5008.5 5026.9 5072.4 4858.7 4910.6 5022.6 5123.9 5155.1 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:4392.40 Median:4916.81 stddev:1308.56 (26.6%) stddev-sans-first:1275.94 04:44:43 INFO - PROCESS | 1675 | Values: 315.0 341.6 351.4 362.9 369.5 4460.8 5006.8 5138.3 5227.5 5386.6 4836.5 5261.7 5268.5 5277.2 5289.8 3437.9 3840.6 4152.6 4177.4 4201.7 3400.5 3613.3 3999.9 4016.0 4140.0 4050.0 4055.1 4063.5 4066.8 4087.2 4058.3 4083.4 4090.7 4101.3 4121.2 2887.8 3405.9 3474.4 3577.1 3613.3 4937.9 5016.8 5029.7 5034.4 5085.2 4081.7 4507.5 4664.7 4691.5 4707.9 5153.7 5345.5 5531.0 5591.4 5647.0 3994.2 3999.9 4015.2 4022.6 4022.7 4945.6 5367.6 5424.6 5812.5 5843.6 3394.9 3428.5 3943.1 3984.0 4092.8 4110.9 4138.6 4144.3 4150.6 4160.3 4695.2 4706.8 4779.5 4783.2 4787.8 5165.9 5599.5 5615.2 5649.7 5729.1 1257.6 1257.8 1259.4 1265.6 1273.7 725.8 855.2 860.0 872.6 874.9 4589.9 4631.6 4872.6 4905.3 4930.9 4898.8 5376.7 5413.9 5466.6 5566.9 4543.5 4850.6 4936.8 5077.9 5099.4 2294.4 2394.7 2733.6 2812.0 2844.9 5512.9 5581.1 5600.7 5621.9 5646.4 4924.7 5169.6 5216.6 5515.3 5521.9 4783.6 5045.9 5575.3 5656.4 5722.7 4521.8 4736.1 4910.5 5105.4 5471.4 4298.6 4643.6 5373.7 5375.7 5377.4 4260.8 4477.4 5207.3 5540.8 5609.1 3422.6 3581.5 3616.8 3631.2 3637.1 5125.6 5404.2 5452.2 5470.7 5499.5 4890.3 5127.3 5176.8 5297.0 5327.8 5263.4 5263.6 5271.4 5305.2 5317.7 4500.1 4923.2 5066.9 5129.3 5173.7 4671.9 5099.7 5220.2 5323.9 5330.9 4499.9 4973.4 5217.7 5261.1 5265.3 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:20704.22 Median:24303.23 stddev:30139.04 (124.0%) stddev-sans-first:30186.11 04:44:43 INFO - PROCESS | 1675 | Values: 688.0 694.3 748.6 797.4 827.6 14831.3 15044.6 15513.8 15803.5 15812.3 13925.9 14171.6 14387.3 14400.2 14400.8 849.8 878.3 881.3 881.6 883.3 675.3 693.1 699.4 837.1 870.6 926.8 927.2 928.3 929.0 933.9 927.1 927.5 927.7 928.9 928.9 516.9 533.0 665.5 689.6 691.8 14559.9 15101.5 15103.6 15149.4 15150.2 11639.7 11837.8 13724.9 13925.9 14080.7 12542.8 12678.5 12701.7 12809.5 13248.5 4297.6 4313.5 4424.1 4507.8 4509.2 109364.9 109963.5 110347.4 113279.4 113592.9 10176.4 10586.6 10617.5 10793.9 11268.4 17456.2 20761.7 21054.1 21187.9 21264.5 7295.3 7403.4 7499.7 7570.6 7601.3 4227.1 4337.7 4341.2 4341.9 4343.2 3469.6 3517.7 3530.0 3564.0 3572.9 590.9 597.4 602.4 606.6 614.4 546.8 597.3 630.4 659.9 673.1 9170.7 9369.2 9410.6 9715.4 9964.8 627.6 638.3 652.2 658.8 672.4 3936.7 4655.6 4703.7 4704.3 4745.9 80346.4 95893.0 99130.5 101308.5 103084.0 95951.0 96516.0 97169.5 97447.0 97573.5 78729.2 78944.2 83959.0 83975.6 84837.6 8550.9 9163.1 9168.7 9183.0 9238.5 44182.3 44477.8 52460.0 53833.0 54628.2 62813.7 63045.0 69185.7 72028.6 72095.6 12356.3 12777.7 12901.9 12933.2 13224.9 14353.6 14522.1 14640.3 14815.0 15105.3 13597.6 13662.1 13736.7 13769.3 13944.7 13170.0 13543.8 13569.9 13693.9 13734.7 13091.3 13235.7 13311.6 13568.3 13899.7 10395.5 11013.8 11078.0 11428.0 11631.7 10100.2 10507.3 10618.1 11316.7 11628.4 04:44:43 INFO - PROCESS | 1675 | -------- Summary: end -------- 04:44:43 INFO - PROCESS | 1675 | 04:44:43 INFO - TEST-INFO | 1675: exit 0 04:44:43 INFO - TEST-OK | dromaeo_css | took 1119184ms 04:44:43 INFO - TEST-START | kraken 04:44:43 INFO - operating with platform_type : mac_ 04:44:43 INFO - Initialising browser for kraken test... 04:44:43 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmpulBWLI/profile http://localhost:49242/getInfo.html) 04:44:44 INFO - PROCESS | 1682 | 1483793084351 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 04:44:46 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 04:44:46 INFO - PROCESS | 1682 | colorDepth:24 04:44:46 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 04:44:46 INFO - PROCESS | 1682 | __metrics 04:44:46 INFO - PROCESS | 1682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:44:46 INFO - PROCESS | 1682 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:44:46 INFO - TEST-INFO | 1682: exit 0 04:44:46 INFO - Browser initialized. 04:44:46 INFO - Running cycle 1/1 for kraken test... 04:44:46 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/tmpulBWLI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:58 INFO - PROCESS | 1684 | RSS: Main: 234700800 04:44:58 INFO - PROCESS | 1684 | 04:45:01 INFO - PROCESS | 1684 | 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) 04:45:02 INFO - PROCESS | 1684 | RSS: Main: 286748672 04:45:02 INFO - PROCESS | 1684 | 04:45:05 INFO - PROCESS | 1684 | 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) 04:45:06 INFO - PROCESS | 1684 | RSS: Main: 285929472 04:45:06 INFO - PROCESS | 1684 | 04:45:09 INFO - PROCESS | 1684 | 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) 04:45:10 INFO - PROCESS | 1684 | RSS: Main: 382996480 04:45:10 INFO - PROCESS | 1684 | 04:45:12 INFO - PROCESS | 1684 | 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) 04:45:12 INFO - PROCESS | 1684 | RSS: Main: 283299840 04:45:12 INFO - PROCESS | 1684 | 04:45:14 INFO - PROCESS | 1684 | 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) 04:45:15 INFO - PROCESS | 1684 | RSS: Main: 287191040 04:45:15 INFO - PROCESS | 1684 | 04:45:18 INFO - PROCESS | 1684 | 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) 04:45:18 INFO - PROCESS | 1684 | RSS: Main: 328429568 04:45:18 INFO - PROCESS | 1684 | 04:45:21 INFO - PROCESS | 1684 | 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) 04:45:22 INFO - PROCESS | 1684 | RSS: Main: 329306112 04:45:22 INFO - PROCESS | 1684 | 04:45:25 INFO - PROCESS | 1684 | 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) 04:45:26 INFO - PROCESS | 1684 | RSS: Main: 327979008 04:45:26 INFO - PROCESS | 1684 | 04:45:27 INFO - PROCESS | 1684 | 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) 04:45:28 INFO - PROCESS | 1684 | RSS: Main: 303050752 04:45:28 INFO - PROCESS | 1684 | 04:45:30 INFO - PROCESS | 1684 | 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) 04:45:30 INFO - PROCESS | 1684 | RSS: Main: 305254400 04:45:30 INFO - PROCESS | 1684 | 04:45:32 INFO - PROCESS | 1684 | 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) 04:45:33 INFO - PROCESS | 1684 | RSS: Main: 306987008 04:45:33 INFO - PROCESS | 1684 | 04:45:36 INFO - PROCESS | 1684 | 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) 04:45:37 INFO - PROCESS | 1684 | RSS: Main: 323768320 04:45:37 INFO - PROCESS | 1684 | 04:45:40 INFO - PROCESS | 1684 | 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) 04:45:40 INFO - PROCESS | 1684 | RSS: Main: 317849600 04:45:40 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | 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) 04:45:42 INFO - PROCESS | 1684 | RSS: Main: 361484288 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | __start_tp_report 04:45:42 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 04:45:42 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 04:45:42 INFO - PROCESS | 1684 | |i|pagename|runs| 04:45:42 INFO - PROCESS | 1684 | |0;ai-astar;113;114;114;113;114;113;113;114;114;114 04:45:42 INFO - PROCESS | 1684 | |1;audio-beat-detection;142;114;134;174;147;105;129;137;116;128 04:45:42 INFO - PROCESS | 1684 | |2;audio-dft;182;184;183;208;270;184;198;246;261;181 04:45:42 INFO - PROCESS | 1684 | |3;audio-fft;79;59;59;85;61;60;57;58;73;58 04:45:42 INFO - PROCESS | 1684 | |4;audio-oscillator;90;70;85;86;101;100;101;69;68;85 04:45:42 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;88;86;89;87;89;86;90;87;89;86 04:45:42 INFO - PROCESS | 1684 | |6;imaging-darkroom;94;95;95;98;98;88;131;157;90;94 04:45:42 INFO - PROCESS | 1684 | |7;imaging-desaturate;85;90;85;87;85;85;87;86;104;119 04:45:42 INFO - PROCESS | 1684 | |8;json-parse-financial;56;57;56;58;56;54;54;52;56;55 04:45:42 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;72;68;71;74;72;69;70;70;70;71 04:45:42 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;88;87;88;87;86;87;83;86;84;87 04:45:42 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;141;142;161;133;139;141;140;139;177;155 04:45:42 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;171;170;185;173;168;170;170;170;173;171 04:45:42 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;66;67;67;67;68;69;75;84;87;69 04:45:42 INFO - PROCESS | 1684 | __end_tp_report 04:45:42 INFO - PROCESS | 1684 | __start_cc_report 04:45:42 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,2082 04:45:42 INFO - PROCESS | 1684 | __end_cc_report 04:45:42 INFO - PROCESS | 1684 | __startTimestamp1483793142864__endTimestamp 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | ------- Summary: start ------- 04:45:42 INFO - PROCESS | 1684 | Number of tests: 14 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:45:42 INFO - PROCESS | 1684 | Values: 113.0 114.0 114.0 113.0 114.0 113.0 113.0 114.0 114.0 114.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:135.50 stddev:19.57 (14.4%) stddev-sans-first:20.46 04:45:42 INFO - PROCESS | 1684 | Values: 142.0 114.0 134.0 174.0 147.0 105.0 129.0 137.0 116.0 128.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:209.70 Median:203.00 stddev:35.51 (17.5%) stddev-sans-first:36.22 04:45:42 INFO - PROCESS | 1684 | Values: 182.0 184.0 183.0 208.0 270.0 184.0 198.0 246.0 261.0 181.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:64.90 Median:60.50 stddev:10.19 (16.8%) stddev-sans-first:9.45 04:45:42 INFO - PROCESS | 1684 | Values: 79.0 59.0 59.0 85.0 61.0 60.0 57.0 58.0 73.0 58.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:77.50 stddev:13.04 (16.8%) stddev-sans-first:13.73 04:45:42 INFO - PROCESS | 1684 | Values: 90.0 70.0 85.0 86.0 101.0 100.0 101.0 69.0 68.0 85.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:1.49 (1.7%) stddev-sans-first:1.58 04:45:42 INFO - PROCESS | 1684 | Values: 88.0 86.0 89.0 87.0 89.0 86.0 90.0 87.0 89.0 86.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:94.50 stddev:22.17 (23.5%) stddev-sans-first:23.22 04:45:42 INFO - PROCESS | 1684 | Values: 94.0 95.0 95.0 98.0 98.0 88.0 131.0 157.0 90.0 94.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:85.50 stddev:11.32 (13.2%) stddev-sans-first:11.78 04:45:42 INFO - PROCESS | 1684 | Values: 85.0 90.0 85.0 87.0 85.0 85.0 87.0 86.0 104.0 119.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:56.00 stddev:1.71 (3.1%) stddev-sans-first:1.80 04:45:42 INFO - PROCESS | 1684 | Values: 56.0 57.0 56.0 58.0 56.0 54.0 54.0 52.0 56.0 55.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:1.70 (2.4%) stddev-sans-first:1.74 04:45:42 INFO - PROCESS | 1684 | Values: 72.0 68.0 71.0 74.0 72.0 69.0 70.0 70.0 70.0 71.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:87.00 stddev:1.64 (1.9%) stddev-sans-first:1.62 04:45:42 INFO - PROCESS | 1684 | Values: 88.0 87.0 88.0 87.0 86.0 87.0 83.0 86.0 84.0 87.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:141.50 stddev:13.46 (9.5%) stddev-sans-first:14.11 04:45:42 INFO - PROCESS | 1684 | Values: 141.0 142.0 161.0 133.0 139.0 141.0 140.0 139.0 177.0 155.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:171.00 stddev:4.77 (2.8%) stddev-sans-first:5.04 04:45:42 INFO - PROCESS | 1684 | Values: 171.0 170.0 185.0 173.0 168.0 170.0 170.0 170.0 173.0 171.0 04:45:42 INFO - PROCESS | 1684 | 04:45:42 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:69.00 stddev:7.62 (11.0%) stddev-sans-first:7.78 04:45:42 INFO - PROCESS | 1684 | Values: 66.0 67.0 67.0 67.0 68.0 69.0 75.0 84.0 87.0 69.0 04:45:42 INFO - PROCESS | 1684 | -------- Summary: end -------- 04:45:42 INFO - PROCESS | 1684 | 04:45:43 INFO - TEST-INFO | 1684: exit 0 04:45:43 INFO - TEST-OK | kraken | took 59948ms 04:45:43 INFO - SUITE-END | took 1179s 04:45:44 INFO - Completed test suite (00:19:39) 04:45:44 INFO - javascript benchmark 04:45:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.917377065573, 3305.140663427509, 3326.3846188455136, 3345.983270083649, 3371.157210697326, 5656.462636049569, 5672.659640421578, 5687.658740475575, 5751.223584816049, 5761.971190144049, 5693.060644993581, 5709.514691251248, 5800.709964501769, 5805.10020946754, 5816.767329306823, 5139.460356662553, 5166.948330516684, 5204.895902081956, 5225.503577160163, 5234.764435600388, 5289.365276166865, 5292.179712144623, 5292.947070529284, 5293.285406470135, 5339.359276886779, 5261.552768014723, 5272.367315922113, 5287.894242115155, 5297.682139071649, 5299.417064122943, 5156.767945442464, 5173.22401639757, 5191.506806853381, 5191.766370513336, 5206.24509446132, 5197.766100525486, 5224.268602395668, 5248.685078895279, 5271.367435907678, 5284.550813180885, 4727.409073865767, 5702.971485142548, 5706.315242170925, 5708.65748055118, 5718.885622287573, 6137.386261373871, 6144.3103146634585, 6152.538559608047, 6290.748370065192, 6310.5267104967315, 6223.657698826562, 6250.499960003189, 6287.245530536319, 6341.524385671093, 6356.809295721136, 5815.244018277597, 5848.181254624356, 5874.471297583238, 5881.794137205177, 5907.556933229982, 6250.937490625036, 6297.370262973666, 6301.684915754286, 6304.936950630435, 6315.273743519462, 6047.7278522466595, 6102.481289090389, 6112.480439162721, 6115.575014303891, 6168.660723660242, 6114.266954506099, 6295.087212354183, 6305.211848518935, 6306.180196574416, 6317.905231421532, 6105.763021687015, 6117.675163990783, 6127.080937859268, 6148.8770224595255, 6210.782622608187, 6304.022876454157, 6308.116863639094, 6313.0, 6316.242050953915, 6317.0, 4903.8528844134735, 4954.281629163823, 4962.478939711278, 5009.098362294749, 5036.622253330942, 4427.933580996288, 4459.732416055047, 4486.057927835126, 4489.865304040884, 4489.910201795946, 5702.54379649627, 5715.685637289906, 5753.453421924911, 5763.711814409263, 5819.127130930309, 6301.338359472281, 6304.338044505261, 6306.936930630635, 6315.242170939425, 6319.241690997018, 5717.828465146053, 5751.4536119068625, 5758.654480731169, 5763.769449221984, 5767.653940763567, 5285.101532739443, 5326.733663316896, 5328.973355133199, 5425.701586412706, 5444.101723215705, 6233.2208473940755, 6258.217722784652, 6276.686165691697, 6338.366163383625, 6339.302676705651, 6186.443220110212, 6257.405546473078, 6282.811515654537, 6301.96849015752, 6312.621242725451, 6261.592996455216, 6311.621302721851, 6318.399752023551, 6335.303116657256, 6337.556371053982, 6105.15650299495, 6111.327753947151, 6134.712129681229, 6165.229346331708, 6239.407256310643, 5249.973750131225, 5412.188171774108, 6306.211723534559, 6326.873462530815, 6340.714667839866, 6094.0, 6153.630782153085, 6165.167702360124, 6166.136740856194, 6173.753049877954, 5020.071286811952, 5986.9401305986385, 6024.849378765391, 6025.517958563392, 6119.84700382494, 5945.524358051432, 5948.226730525177, 5978.312494063064, 5984.910226346696, 6064.332923378337, 5971.373005834499, 6038.215032045806, 6054.727537260922, 6129.2032035835055, 6154.1999540059505, 5924.7926322578505, 5930.584859059997, 5974.253218347707, 5997.0404735242155, 6010.398960103955], "value": 5703.806051692775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.08717828428263, 513.5711680746576, 513.8438513344972, 548.8545535433113, 550.4522999615383, 38158.0, 39931.0, 40123.99690007751, 41973.7901310493, 42262.577374226246, 40591.79704101483, 40689.0, 40697.0, 40703.0, 40758.388624170664, 8448.23965843077, 9184.081591840813, 9373.250139988819, 9404.106609872086, 9418.058194180596, 8526.658933642679, 11073.390963497004, 11315.660530184108, 11320.490577924013, 11475.655730328104, 11171.441427928612, 11213.551457941672, 11213.551457941714, 11220.719482012973, 11226.607068752595, 8407.159284071606, 8623.827523449496, 9547.2839537035, 11234.49444774983, 11318.490667919963, 8562.48625082497, 8624.870626940601, 8626.482411055356, 10788.56845726178, 11133.332000080021, 38765.41851872224, 39094.80452597747, 39110.41334379987, 39195.412068818994, 39324.0, 3493.493443450711, 3917.138229589486, 3921.117748506592, 3932.646061854447, 3983.960160398388, 28859.134225973256, 29323.560146597818, 29597.0, 29668.851655741582, 29951.400971980438, 3175.952360714591, 3181.236503239206, 3188.2826364068133, 3253.8535765890592, 3285.2608163163336, 347776.8334749782, 348917.0, 350800.0, 353468.0, 354080.2295988555, 54959.0, 55394.44605553974, 55419.0, 55423.0, 55470.722646387316, 58388.0, 58523.70738146282, 58590.82818343609, 58624.41375586275, 58670.0, 13919.65200869966, 14800.703985920221, 15415.229238538253, 15441.845581544265, 15450.84549154494, 10895.237333386587, 11132.10943124548, 11150.0, 12026.218295810744, 12125.757484850254, 14679.55961321162, 14877.925610371878, 15051.774223386657, 15223.61940951463, 15248.847511524964, 5251.947480525146, 6191.380861913773, 6214.471769899519, 6269.467095296951, 6298.700974144404, 865.6191275838611, 869.1959937058195, 873.2010210657304, 879.7052987249215, 906.6282824042111, 28818.85590572034, 29077.563836542467, 29216.853915730284, 29523.704762952526, 29588.55617165744, 937.901520340368, 980.3872579637726, 981.1807141037172, 1001.1190152665607, 1060.6605886116522, 12523.747625237585, 13028.348582571023, 14930.402783888523, 14972.550823475312, 15356.692866142615, 557008.0, 562300.0, 562992.0, 564980.0, 565144.174279126, 56005.7199714007, 56590.0, 56668.14997775036, 56693.0, 56787.0, 1345.8384993800807, 1399.8320201575875, 1405.859414058586, 1411.364885801373, 1415.3206460898825, 24158.75841241565, 27805.470699111742, 28281.15156545307, 28466.0, 29221.0, 43333.91665208395, 43746.781266093465, 46929.53070469388, 47057.0, 47083.529164707914, 481146.0, 491325.0, 493460.0, 496095.0, 498172.0, 31821.840890795396, 32694.34611307808, 36687.26625467529, 36691.0, 37055.814720926224, 42848.0, 43880.56119438765, 43952.78023609862, 44844.55155448404, 45340.0, 31297.687023129478, 37150.88547343584, 37252.627473726, 37448.06379840417, 38564.6143538561, 36676.0, 36726.44910326401, 37024.81487592545, 37671.24657506889, 38211.80894095623, 31140.065798026095, 35352.469712954844, 35700.821495892356, 36171.457428139125, 36424.90725278246, 31568.842155789072, 32456.83771581127, 32794.67205328012, 32838.507422389164, 32924.01227963165, 19911.701324479855, 21789.67315490237, 23279.3016209514, 23973.160939367037, 24531.38671533226], "value": 19062.696710611162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.9770213787171, 388.33594553313833, 392.3095352180162, 395.42860566481437, 417.79528031264687, 12805.0, 15644.530664080095, 19521.51196220095, 20770.753754774705, 20817.06323215457, 19580.608387832235, 20097.899510502426, 20150.49623759403, 20173.394798156078, 20300.086496107673, 5510.8071217507395, 5712.857178570548, 5764.135379693059, 5845.006348920694, 5846.795362162326, 4810.639202059842, 5718.17086522454, 5822.9417705822825, 5824.825255242328, 5826.621269617462, 5712.600117991742, 5728.570357223225, 5749.798757043504, 5772.5670574707065, 5785.681787501686, 5509.47659972302, 5664.065429204176, 5741.339745929209, 5750.798722044729, 5758.856028599298, 5017.824376146854, 5040.722760248185, 5045.84862454127, 5051.040302342543, 5056.241563765428, 20138.69791953122, 22825.315240542644, 24249.030038798428, 24970.625440618405, 25227.747722522723, 2556.463142740027, 2568.3707491664477, 2604.791616670662, 2614.9869250653815, 2689.744474274941, 10120.696379108565, 10341.327813692085, 10697.411642359668, 10763.19276054299, 15534.456294029655, 1832.660957722812, 1845.335679155503, 1866.346778627483, 1874.8875067495994, 1879.5113270549755, 127746.47676087008, 134256.65743342636, 137897.31051344678, 140580.0, 142704.58363330908, 11477.36874471912, 11549.595764148215, 11645.6506304811, 11665.766684666429, 11915.52337906474, 13134.540291089766, 14892.180930048951, 14914.776278355834, 14917.328720207617, 14920.253987300592, 7591.8861217081785, 7794.688212471551, 10462.058414742709, 10938.781224375469, 10981.560737570568, 6717.899231511531, 7404.518706284074, 8278.544680042656, 8700.651973920973, 8716.607752651145, 8592.742217733477, 9176.852893388326, 9692.321537492307, 9828.0, 9951.502424878727, 3958.4853968984426, 4002.5397079335667, 4043.029672878547, 4063.216696562864, 4070.9796451017555, 896.8968568614563, 903.1284810158229, 943.2783920300839, 967.9661211857692, 1011.9342242754344, 15295.770563441558, 18553.721694174597, 19663.901680491505, 20122.819553192683, 21166.153353865982, 850.2517784349733, 1001.4592120254981, 1026.9332493387906, 1067.4555976452066, 1073.6886302972207, 9908.504574771232, 10626.096781773482, 10644.604963883065, 10650.627228046982, 10694.572217111228, 642231.7888410528, 661483.0, 665871.0, 668402.0, 673673.0, 107166.92833071726, 130078.0, 136506.0, 149552.0, 154786.0, 1007.1187710753092, 1013.9949300253452, 1032.607609108534, 1035.8394448860438, 1037.0459177556515, 17817.73273400874, 18245.0, 18280.360187393377, 18372.448826535227, 18440.631187375908, 58818.41181588129, 58858.41141588529, 59084.0, 59459.70270148795, 60067.39932600793, 84187.5790621043, 86287.13712862792, 86390.0, 86461.70307445519, 86488.5675571618, 6777.593344399154, 7002.614856182959, 7928.960355198187, 7933.920660793549, 8132.512049277062, 9729.659461918798, 11402.144839137263, 12224.083193760682, 12598.118131731058, 12621.558245461365, 9036.186743193144, 10225.897741022793, 10749.140068794633, 10915.399653018843, 10921.617743378943, 8229.629666664894, 10469.319494233156, 10681.412522311348, 10841.457927103771, 10924.890751092387, 8936.955315223642, 10623.89376106229, 10996.450177491253, 11002.50488727993, 11018.944905275743, 7854.0, 8005.039395272604, 8028.0, 8208.302294305051, 8376.53929033909, 9821.85267220978, 10224.744381390525, 10529.894701053197, 10583.4708264588, 10602.416867072077], "value": 9966.904351342264, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.5667543061645, 1411.632975426389, 1424.2166808255456, 1424.914505129693, 1432.3697573067845, 8000.0, 8439.409241353107, 8445.535495547743, 8456.450330728507, 8479.024912135106, 6699.899501507482, 7415.0, 7611.581363025031, 7612.885806712904, 8256.133106023855, 2303.078768492608, 2336.6261398176293, 2354.9764502355015, 2358.8938497767554, 2362.078789272185, 2322.7677232276724, 2365.1485465232504, 2368.159303447277, 2375.9643605345846, 2377.8454400464, 2055.68136938774, 2130.7975742304457, 2324.9651255231183, 2349.8120150388013, 2351.6013666060608, 2254.9323520294415, 2329.219711396676, 2329.6389059695775, 2338.918137865184, 2339.5320935812906, 2320.640300753386, 2341.8477798943013, 2343.484433424644, 2349.4126468382906, 2351.012574718622, 8187.631556579968, 8332.958335208346, 8342.499450032958, 8362.540060296682, 8438.831223375559, 5042.445331013622, 5157.53582177602, 5216.8695782605355, 5218.817341393071, 5230.97384513075, 3264.2165880188822, 3460.913477163066, 3493.493443450711, 3500.6674365935196, 3538.4161613333767, 1185.2884782987667, 1208.2689972566645, 1247.812828075796, 1260.3005332040714, 1265.1270623269913, 10642.57429702811, 10920.890791092146, 10930.344179349186, 10930.726731831688, 10942.288751231144, 4511.255642818898, 4636.095961287584, 4673.976630116827, 4725.598324142417, 4769.093872164278, 8733.820934173806, 8784.341174411944, 8788.78028049291, 8792.692255771017, 8801.163889430494, 4494.617957473652, 4990.0, 5004.224345226496, 5135.820246291362, 5149.76826042829, 4624.676272660881, 4743.525647435229, 4805.37530121089, 4816.710997340171, 4817.373741413594, 3564.180238545149, 3628.292482965849, 3651.634836516327, 3687.1519550503, 3701.3892707703467, 1143.348291473852, 1152.6426807689643, 1159.652104368686, 1167.241293159453, 1169.2633640806407, 756.5082696247373, 866.2160744526213, 881.4843316659826, 883.8983516895511, 896.4800415758845, 4063.878083657495, 5338.973305133527, 5344.0, 5368.32895888014, 5473.67157970523, 481.86748644123145, 579.1949190625035, 589.028103629007, 920.4707293306376, 922.2483675804198, 3561.554805649294, 3684.649958254015, 3786.962130378716, 3793.487879136281, 3804.144067584771, 7363.7422690205585, 9719.708408747749, 9919.702408927744, 9949.502524873873, 9991.900080999098, 9757.512124393752, 9817.214622830157, 9919.454430006419, 9935.602575897028, 9938.45338506389, 7464.402847772165, 7675.53946763196, 7703.1911649277135, 7781.766547003599, 7965.800854978556, 4593.96635756952, 5510.448955104457, 5545.584081193846, 5673.092305231144, 5891.528677705687, 9197.0, 9255.305852061258, 9292.95353523228, 9293.30300227472, 9301.581428835854, 8411.83176336482, 8976.326775491994, 9386.953065234904, 9404.200642945152, 9433.95283023608, 6610.537262391586, 6837.589744615339, 6838.589684618939, 6840.213375461885, 6840.931590684029, 7233.710651573878, 7446.7021319146625, 7533.171351151259, 7614.0, 7671.923280767121, 7068.151821781419, 7107.182673992557, 7179.138503379628, 7216.3505284524645, 7234.782956511313, 6929.0, 6965.303469652994, 7038.859222815618, 7076.080109585927, 7133.714651414093, 6278.623282603058, 6399.424051835357, 6399.55203135776, 6409.871802563829, 6425.9036114457385, 3890.7276490646514, 4855.077535268288, 5008.524190201925, 5026.924596131134, 5072.416672082758, 4858.708477491362, 4910.582600479, 5022.623303252198, 5123.923141152811, 5155.123628983007], "value": 4314.827392921128, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.9921251968702, 341.5832684125366, 351.4412084785192, 362.86936702787, 369.51593412629455, 4460.776961151938, 5006.77334053157, 5138.306328645632, 5227.4511176326505, 5386.596005299603, 4836.467988521269, 5261.684298942067, 5268.4994925482115, 5277.234800953859, 5289.761960711768, 3437.896863094105, 3840.577536470986, 4152.6055024772595, 4177.352510360899, 4201.663866890642, 3400.4899265110307, 3613.2900324756433, 3999.880003599882, 4015.9598404016024, 4140.047789008529, 4049.9797501012454, 4055.1078762672164, 4063.5168163620215, 4066.8373265069363, 4087.2438598859308, 4058.3100872851537, 4083.3874918762276, 4090.672746180328, 4101.343784994387, 4121.237571049365, 2887.754540864029, 3405.872262336936, 3474.3572439098593, 3577.123604716835, 3613.295407395559, 4937.901241975141, 5016.84612539118, 5029.723365214912, 5034.446210916833, 5085.237214417867, 4081.7346872453195, 4507.459104907432, 4664.72011679297, 4691.507391723888, 4707.882302942419, 5153.66501177426, 5345.541051116491, 5530.953547187566, 5591.384947655754, 5646.971765141148, 3994.2410941920944, 3999.8800035998966, 4015.1768887378016, 4022.617851304151, 4022.738521996061, 4945.604351651859, 5367.5705943524035, 5424.593155513352, 5812.534997200214, 5843.561732870052, 3394.932101357959, 3428.4857271409487, 3943.093088589583, 3984.023914141054, 4092.8362865485046, 4110.917781644351, 4138.5654506276405, 4144.295469770146, 4150.584941505826, 4160.271952408317, 4695.24876019842, 4706.788194531255, 4779.498152693917, 4783.1868582341085, 4787.760611969457, 5165.870853228625, 5599.480233894764, 5615.1643420072605, 5649.661020338793, 5729.1119876419225, 1257.6415721519322, 1257.8176514076922, 1259.3766085787595, 1265.6266401411422, 1273.7008251583334, 725.8403151306704, 855.2388374346837, 859.9957000214958, 872.5637181409295, 874.9107361335178, 4589.908201835945, 4631.564215093332, 4872.561469467765, 4905.337779399735, 4930.8767280818265, 4898.779554920037, 5376.731163441812, 5413.945860541344, 5466.562674985991, 5566.860828479239, 4543.45478542577, 4850.587700045465, 4936.802527898843, 5077.918053598007, 5099.4390617032095, 2294.38051726033, 2394.712634483873, 2733.58996150575, 2811.9578206326923, 2844.900428484993, 5512.944870551323, 5581.05122129239, 5600.6919619420505, 5621.8594535136945, 5646.378898321182, 4924.655274130777, 5169.638125331266, 5216.60875434344, 5515.310586176728, 5521.944780552143, 4783.56947874686, 5045.940352525938, 5575.275214222206, 5656.406077361982, 5722.713864306851, 4521.751303678329, 4736.147493451212, 4910.459849416633, 5105.412877518985, 5471.398146203994, 4298.63461605767, 4643.558861908114, 5373.662456831291, 5375.650582712111, 5377.408485066561, 4260.826978241057, 4477.366255144033, 5207.3230480037355, 5540.833774986757, 5609.102543593006, 3422.589289285202, 3581.534400528019, 3616.782993020322, 3631.237440137495, 3637.090327737852, 5125.564327032244, 5404.189371594292, 5452.182172674131, 5470.671759694431, 5499.532539734168, 4890.33980412654, 5127.333446651912, 5176.844694659167, 5296.973515132399, 5327.813526526553, 5263.36839579253, 5263.605229607718, 5271.393789714232, 5305.204219367126, 5317.734113294242, 4500.099980004078, 4923.153817318505, 5066.873328166826, 5129.307543481582, 5173.7494190027455, 4671.883202919818, 5099.730619874121, 5220.1647736362, 5323.920141197963, 5330.9466905332, 4499.910001800011, 4973.428055773632, 5217.660852044605, 5261.131913234273, 5265.262863199232], "value": 3982.791373761697, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.9587224766517, 694.291822341212, 748.6069813347991, 797.42186914487, 827.569663774837, 14831.332590033448, 15044.623884402896, 15513.767293490606, 15803.525894223163, 15812.288447019882, 13925.930370348135, 14171.574852754435, 14387.280635968185, 14400.20798856065, 14400.783988240159, 849.8427790858696, 878.2666473494639, 881.3478026260585, 881.5636260051284, 883.2889523933231, 675.2909445082668, 693.098971337262, 699.433458898294, 837.0624900111883, 870.5951732444412, 926.7683079230193, 927.2303987690235, 928.3269629518579, 928.9688445825128, 933.8505839065751, 927.1099744245498, 927.452802846324, 927.6984979881498, 928.8792456980583, 928.9024652411467, 516.888868893186, 533.0352062766406, 665.487574567585, 689.5724650716538, 691.8270432391902, 14559.927200364036, 15101.546953591409, 15103.622409439742, 15149.394024239018, 15150.242487875563, 11639.6508104757, 11837.822432663517, 13724.93137534306, 13925.871178450563, 14080.71838563223, 12542.81185782214, 12678.492860285578, 12701.682457938534, 12809.487620495169, 13248.536301229411, 4297.634701050383, 4313.525512193688, 4424.115176964588, 4507.774611269423, 4509.1658043262105, 109364.90635093627, 109963.45018274858, 110347.44826275898, 113279.43360283146, 113592.86407135989, 10176.389416635026, 10586.57653693859, 10617.522211500502, 10793.946030269955, 11268.380239086766, 17456.214470348867, 20761.688574671392, 21054.05256763449, 21187.94060296979, 21264.468388290417, 7295.306945840137, 7403.407727381797, 7499.66251518672, 7570.583617900958, 7601.277878601524, 4227.112306415625, 4337.7397356158735, 4341.240282950471, 4341.934870976938, 4343.153085148366, 3469.5836499619704, 3517.680869673872, 3529.964700353015, 3563.964360356415, 3572.9464058039152, 590.8995470769979, 597.375742349246, 602.3885755957706, 606.6481440764346, 614.3764079459353, 546.8461546137646, 597.3280059932575, 630.4294613374904, 659.9118461133233, 673.0678010954863, 9170.724878253663, 9369.203617692436, 9410.576524056434, 9715.36850104741, 9964.80070398588, 627.645380360095, 638.3488841381765, 652.2233988855792, 658.7727234104226, 672.4485921544286, 3936.651358572584, 4655.557722016403, 4703.717776933395, 4704.341392205094, 4745.905081898342, 80346.3930721394, 95893.0, 99130.5043474778, 101308.49345753371, 103083.96916030743, 95951.0404895956, 96516.0, 97169.5424568632, 97447.0, 97573.53639695268, 78729.21270787218, 78944.21055789484, 83959.0, 83975.580122099, 84837.57581212054, 8550.888384509974, 9163.083691630784, 9168.679096231599, 9183.0, 9238.538073096453, 44182.33726494169, 44477.777611111735, 52460.0, 53833.0, 54628.18057729058, 62813.68593157005, 63045.0, 69185.65407172932, 72028.63985680247, 72095.63952180206, 12356.320402377596, 12777.680557986125, 12901.935490322488, 12933.224006559636, 13224.867751322749, 14353.569392918229, 14522.056066355653, 14640.26798660084, 14815.037022593497, 15105.32026058852, 13597.592072237849, 13662.11196272239, 13736.65658358549, 13769.31153442304, 13944.721105578035, 13170.0, 13543.79684304756, 13569.932150339186, 13693.931530342285, 13734.725305494034, 13091.345432728516, 13235.735285294433, 13311.600651980456, 13568.253746044062, 13899.652508687364, 10395.532201050819, 11013.83479247828, 11078.003143329008, 11428.0, 11631.709207269885, 10100.242481813746, 10507.31702439339, 10618.097461715532, 11316.660500184678, 11628.360440175533], "value": 7450.787535086155, "unit": "score"}], "name": "dromaeo_css", "value": 7195.348120740537, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 134.0, 174.0, 147.0, 105.0, 129.0, 137.0, 116.0, 128.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 208.0, 270.0, 184.0, 198.0, 246.0, 261.0, 181.0], "value": 209.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 85.0, 61.0, 60.0, 57.0, 58.0, 73.0, 58.0], "value": 64.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 85.0, 86.0, 101.0, 100.0, 101.0, 69.0, 68.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 98.0, 98.0, 88.0, 131.0, 157.0, 90.0, 94.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 85.0, 87.0, 85.0, 85.0, 87.0, 86.0, 104.0, 119.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 58.0, 56.0, 54.0, 54.0, 52.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 71.0, 74.0, 72.0, 69.0, 70.0, 70.0, 70.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 87.0, 86.0, 87.0, 83.0, 86.0, 84.0, 87.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 161.0, 133.0, 139.0, 141.0, 140.0, 139.0, 177.0, 155.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 185.0, 173.0, 168.0, 170.0, 170.0, 170.0, 173.0, 171.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 68.0, 69.0, 75.0, 84.0, 87.0, 69.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1492.5, "alertThreshold": 2.0}]} 04:45:44 INFO - Return code: 0 04:45:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:45:44 INFO - # TBPL SUCCESS # 04:45:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:44 INFO - 2017-01-07 04:45:44.183 screenresolution[1688:12646] starting screenresolution argv=screenresolution get 04:45:44 INFO - 2017-01-07 04:45:44.186 screenresolution[1688:12646] Display 0: 1600x1200x32@60 04:45:44 INFO - 2017-01-07 04:45:44.192 screenresolution[1689:12649] starting screenresolution argv=screenresolution list 04:45:44 INFO - Available Modes on Display 0 04:45:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:44 INFO - Intel Iris: 04:45:44 INFO - Chipset Model: Intel Iris 04:45:44 INFO - Type: GPU 04:45:44 INFO - Bus: Built-In 04:45:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:44 INFO - Vendor: Intel (0x8086) 04:45:44 INFO - Device ID: 0x0a2e 04:45:44 INFO - Revision ID: 0x0009 04:45:44 INFO - Displays: 04:45:44 INFO - Display: 04:45:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:44 INFO - Display Serial Number: 1600x1200 60 04:45:44 INFO - Main Display: Yes 04:45:44 INFO - Mirror: Off 04:45:44 INFO - Online: Yes 04:45:44 INFO - Rotation: Supported 04:45:44 INFO - Return code: 0 04:45:44 INFO - Running post-action listener: _resource_record_post_action 04:45:44 INFO - [mozharness: 2017-01-07 12:45:44.429560Z] Finished run-tests step (success) 04:45:44 INFO - Running post-run listener: _resource_record_post_run 04:45:44 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 31378432; Write bytes: 551824384; Read time: 191; Write time: 1661 04:45:44 INFO - TinderboxPrint: CPU usage
53.9% 04:45:44 INFO - TinderboxPrint: I/O read bytes / time
31,378,432 / 191 04:45:44 INFO - TinderboxPrint: I/O write bytes / time
551,824,384 / 1,661 04:45:44 INFO - TinderboxPrint: CPU idle
2,217.1 (46.1%) 04:45:44 INFO - TinderboxPrint: CPU system
962.1 (20.0%) 04:45:44 INFO - TinderboxPrint: CPU user
1,630.5 (33.9%) 04:45:44 INFO - TinderboxPrint: Swap in / out
276,213,760 / 0 04:45:44 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243739136; Write bytes: 237225472; Read time: 17636; Write time: 509 04:45:44 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29309952; Write bytes: 299120128; Read time: 174; Write time: 1108 04:45:44 INFO - Running post-run listener: _upload_blobber_files 04:45:44 INFO - Blob upload gear active. 04:45:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:45:44 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 04:45:44 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'] 04:45:44 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 04:45:45 INFO - (blobuploader) - INFO - Open directory for files ... 04:45:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:45:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:45:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:46 INFO - (blobuploader) - INFO - Done attempting. 04:45:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:45:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:45:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:48 INFO - (blobuploader) - INFO - Done attempting. 04:45:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:45:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:45:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:49 INFO - (blobuploader) - INFO - Done attempting. 04:45:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:45:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:45:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:52 INFO - (blobuploader) - INFO - Done attempting. 04:45:52 INFO - (blobuploader) - INFO - Iteration through files over. 04:45:52 INFO - Return code: 0 04:45:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:45:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:45:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93eee64c7876335491ceeb1cc532087add2b887238709b63214bb6615c90e558e381c7234ed23f8bad504897b89fa87a5209b584bf6af3f30aa81988f372adc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2afe2ab249adf68a054b912d899f8ab9d7058b5f44042ddc3748bfbe1fc2676f2202444000d82f002b6d5b1282bb8b28c6e76c85469f20beaa114e62e759cc63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df63e8cb9a836ee3540e1c627e8e54b6c4b175cd2aed40b052685e8f51a6e79c5c148b6688848981200d16aa81b61c73f5aff8a87210cc86fa0322a0eac69c34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b2fbea2a04037ac9e73eddfc76d0c40bc6df9c9840ecb26aa251904a37e99a10069467e8385a99afd712806c6902f5ec662647dac52d2194224c3c4c73e32ff"} 04:45:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:45:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:45:52 INFO - Contents: 04:45:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93eee64c7876335491ceeb1cc532087add2b887238709b63214bb6615c90e558e381c7234ed23f8bad504897b89fa87a5209b584bf6af3f30aa81988f372adc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2afe2ab249adf68a054b912d899f8ab9d7058b5f44042ddc3748bfbe1fc2676f2202444000d82f002b6d5b1282bb8b28c6e76c85469f20beaa114e62e759cc63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df63e8cb9a836ee3540e1c627e8e54b6c4b175cd2aed40b052685e8f51a6e79c5c148b6688848981200d16aa81b61c73f5aff8a87210cc86fa0322a0eac69c34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b2fbea2a04037ac9e73eddfc76d0c40bc6df9c9840ecb26aa251904a37e99a10069467e8385a99afd712806c6902f5ec662647dac52d2194224c3c4c73e32ff"} 04:45:52 INFO - Running post-run listener: copy_logs_to_upload_dir 04:45:52 INFO - Copying logs to upload dir... 04:45:52 INFO - mkdir: /builds/slave/test/build/upload/logs 04:45:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.123041 ========= master_lag: 0.59 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-07 04:45:53.252869) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 04:45:53.256166) ========= 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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93eee64c7876335491ceeb1cc532087add2b887238709b63214bb6615c90e558e381c7234ed23f8bad504897b89fa87a5209b584bf6af3f30aa81988f372adc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2afe2ab249adf68a054b912d899f8ab9d7058b5f44042ddc3748bfbe1fc2676f2202444000d82f002b6d5b1282bb8b28c6e76c85469f20beaa114e62e759cc63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df63e8cb9a836ee3540e1c627e8e54b6c4b175cd2aed40b052685e8f51a6e79c5c148b6688848981200d16aa81b61c73f5aff8a87210cc86fa0322a0eac69c34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b2fbea2a04037ac9e73eddfc76d0c40bc6df9c9840ecb26aa251904a37e99a10069467e8385a99afd712806c6902f5ec662647dac52d2194224c3c4c73e32ff"} build_url:https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/VkfSHadCR9WPiSdf1QMSzw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93eee64c7876335491ceeb1cc532087add2b887238709b63214bb6615c90e558e381c7234ed23f8bad504897b89fa87a5209b584bf6af3f30aa81988f372adc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2afe2ab249adf68a054b912d899f8ab9d7058b5f44042ddc3748bfbe1fc2676f2202444000d82f002b6d5b1282bb8b28c6e76c85469f20beaa114e62e759cc63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df63e8cb9a836ee3540e1c627e8e54b6c4b175cd2aed40b052685e8f51a6e79c5c148b6688848981200d16aa81b61c73f5aff8a87210cc86fa0322a0eac69c34", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b2fbea2a04037ac9e73eddfc76d0c40bc6df9c9840ecb26aa251904a37e99a10069467e8385a99afd712806c6902f5ec662647dac52d2194224c3c4c73e32ff"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 04:45:53.294113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 04:45:53.294428) ========= 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.eVEGuHbUxs/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.CKEpSmWsXJ/Listeners TMPDIR=/var/folders/_8/27z362bn1ds2qsrfjqy_jf7400000w/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.005181 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 04:45:53.342310) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 04:45:53.342586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 04:45:53.342911) ========= ========= Total master_lag: 0.74 =========