builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0316 starttime: 1491355367.74 results: success (0) buildid: 20170404174627 builduid: 57b84bf3bacd4a4abae9e969b783c8b1 revision: 896e9cfb9d67d6a73e70e39532f31306c22202cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:47.743264) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:47.743863) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:47.744185) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:47.771594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:47.772038) ========= 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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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-04-04 18:22:47-- 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% 18.6M=0.001s 2017-04-04 18:22:48 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.469608 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:48.267258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:48.267689) ========= 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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.025884 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:48.316374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-04 18:22:48.316789) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 896e9cfb9d67d6a73e70e39532f31306c22202cb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 896e9cfb9d67d6a73e70e39532f31306c22202cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kQIQrDBMd2/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.g4xhqUGxMy/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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-04-04 18:22:48,424 truncating revision to first 12 chars 2017-04-04 18:22:48,424 Setting DEBUG logging. 2017-04-04 18:22:48,424 attempt 1/10 2017-04-04 18:22:48,424 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/896e9cfb9d67?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 18:22:49,117 unpacking tar archive at: mozilla-aurora-896e9cfb9d67/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980924 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-04 18:22:49.321932) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:49.322308) ========= script_repo_revision: 896e9cfb9d67d6a73e70e39532f31306c22202cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:49.322766) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:49.323073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 18:22:49.343320) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-04 18:22:49.343683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kQIQrDBMd2/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.g4xhqUGxMy/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:22:49 INFO - MultiFileLogger online at 20170404 18:22:49 in /builds/slave/test 18:22:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:49 INFO - {'append_to_log': False, 18:22:49 INFO - 'base_work_dir': '/builds/slave/test', 18:22:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:49 INFO - 'branch': 'Mozilla-Aurora', 18:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:49 INFO - 'config_files': ('talos/mac_config.py',), 18:22:49 INFO - 'default_actions': ('clobber', 18:22:49 INFO - 'read-buildbot-config', 18:22:49 INFO - 'download-and-extract', 18:22:49 INFO - 'populate-webroot', 18:22:49 INFO - 'create-virtualenv', 18:22:49 INFO - 'install', 18:22:49 INFO - 'run-tests'), 18:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:49 INFO - 'download_minidump_stackwalk': True, 18:22:49 INFO - 'download_symbols': 'ondemand', 18:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:49 INFO - 'gecko_profile': False, 18:22:49 INFO - 'gecko_profile_interval': 0, 18:22:49 INFO - 'installer_path': 'installer.exe', 18:22:49 INFO - 'log_level': 'info', 18:22:49 INFO - 'log_name': 'talos', 18:22:49 INFO - 'log_to_console': True, 18:22:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:22:49 INFO - 'opt_config_files': (), 18:22:49 INFO - 'pip_index': False, 18:22:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:22:49 INFO - 'cmd': ('bash', 18:22:49 INFO - '-c', 18:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:22:49 INFO - 'enabled': True, 18:22:49 INFO - 'halt_on_failure': False, 18:22:49 INFO - 'name': 'check_screen_resolution'},), 18:22:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:22:49 INFO - 'cmd': ('bash', 18:22:49 INFO - '-c', 18:22:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:22:49 INFO - 'enabled': True, 18:22:49 INFO - 'halt_on_failure': False, 18:22:49 INFO - 'name': 'check_screen_resolution'},), 18:22:49 INFO - 'run_cmd_checks_enabled': True, 18:22:49 INFO - 'suite': 'dromaeojs', 18:22:49 INFO - 'system_bits': '32', 18:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:49 INFO - 'title': 't-yosemite-r7-0316', 18:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:49 INFO - 'use_talos_json': True, 18:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:49 INFO - 'work_dir': 'build'} 18:22:49 INFO - [mozharness: 2017-04-05 01:22:49.555783Z] Running clobber step. 18:22:49 INFO - Running pre-action listener: _resource_record_pre_action 18:22:49 INFO - Running main action method: clobber 18:22:49 INFO - rmtree: /builds/slave/test/build 18:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:51 INFO - Running post-action listener: _resource_record_post_action 18:22:51 INFO - [mozharness: 2017-04-05 01:22:51.930933Z] Finished clobber step (success) 18:22:51 INFO - [mozharness: 2017-04-05 01:22:51.931063Z] Running read-buildbot-config step. 18:22:51 INFO - Running pre-action listener: _resource_record_pre_action 18:22:51 INFO - Running main action method: read_buildbot_config 18:22:51 INFO - Using buildbot properties: 18:22:51 INFO - { 18:22:51 INFO - "project": "", 18:22:51 INFO - "product": "firefox", 18:22:51 INFO - "script_repo_revision": "production", 18:22:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:22:51 INFO - "repository": "", 18:22:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:22:51 INFO - "buildid": "20170404174627", 18:22:51 INFO - "pgo_build": "False", 18:22:51 INFO - "basedir": "/builds/slave/test", 18:22:51 INFO - "buildnumber": 42, 18:22:51 INFO - "slavename": "t-yosemite-r7-0316", 18:22:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:22:51 INFO - "platform": "yosemite_r7", 18:22:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:22:51 INFO - "branch": "mozilla-aurora", 18:22:51 INFO - "repo_path": "releases/mozilla-aurora", 18:22:51 INFO - "revision": "896e9cfb9d67d6a73e70e39532f31306c22202cb", 18:22:51 INFO - "stage_platform": "macosx64", 18:22:51 INFO - "builduid": "57b84bf3bacd4a4abae9e969b783c8b1", 18:22:51 INFO - "slavebuilddir": "test" 18:22:51 INFO - } 18:22:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 18:22:51 INFO - Running post-action listener: _resource_record_post_action 18:22:51 INFO - [mozharness: 2017-04-05 01:22:51.934347Z] Finished read-buildbot-config step (success) 18:22:51 INFO - [mozharness: 2017-04-05 01:22:51.934484Z] Running download-and-extract step. 18:22:51 INFO - Running pre-action listener: _resource_record_pre_action 18:22:51 INFO - Running main action method: download_and_extract 18:22:51 INFO - mkdir: /builds/slave/test/build/tests 18:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:51 INFO - trying https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 18:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 18:22:53 INFO - Downloaded 1199 bytes. 18:22:53 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:22:53 INFO - Using the following test package requirements: 18:22:53 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:22:53 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 18:22:53 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 18:22:53 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'jsshell-mac.zip'], 18:22:53 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 18:22:53 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:22:53 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 18:22:53 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 18:22:53 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 18:22:53 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:22:53 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 18:22:53 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:22:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:22:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:22:53 INFO - Fetch https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:22:54 INFO - Expected file size: 51790800 18:22:54 INFO - Obtained file size: 51790800 18:23:10 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:23:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:23:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:23:10 INFO - Fetch https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:23:12 INFO - Expected file size: 51790800 18:23:12 INFO - Obtained file size: 51790800 18:23:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 18:23:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:23:27 INFO - Fetch https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 18:23:28 INFO - Expected file size: 14404438 18:23:28 INFO - Obtained file size: 14404438 18:23:29 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')]} 18:23:29 INFO - trying https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:23:29 INFO - Downloading https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 18:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 18:23:31 INFO - Downloaded 74680046 bytes. 18:23:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:23:31 INFO - mkdir: /builds/slave/test/properties 18:23:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:31 INFO - Contents: 18:23:31 INFO - build_url:https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:23:31 INFO - Running post-action listener: _resource_record_post_action 18:23:31 INFO - Running post-action listener: set_extra_try_arguments 18:23:31 INFO - [mozharness: 2017-04-05 01:23:31.765955Z] Finished download-and-extract step (success) 18:23:31 INFO - [mozharness: 2017-04-05 01:23:31.766069Z] Running populate-webroot step. 18:23:31 INFO - Running pre-action listener: _resource_record_pre_action 18:23:31 INFO - Running main action method: populate_webroot 18:23:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:31 INFO - '--tppagecycles', 18:23:31 INFO - '7'], 18:23:31 INFO - 'tests': ['tsvgm']}}, 18:23:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['damp', 'tps']}, 18:23:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'tests': ['damp', 'tps']}, 18:23:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['dromaeo_dom']}, 18:23:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['basic_compositor_video']}, 18:23:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:23:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['a11yr', 18:23:31 INFO - 'ts_paint', 18:23:31 INFO - 'tpaint', 18:23:31 INFO - 'sessionrestore', 18:23:31 INFO - 'sessionrestore_no_auto_restore', 18:23:31 INFO - 'tabpaint']}, 18:23:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:31 INFO - 'ts_paint', 18:23:31 INFO - 'tpaint', 18:23:31 INFO - 'sessionrestore', 18:23:31 INFO - 'sessionrestore_no_auto_restore', 18:23:31 INFO - 'tabpaint']}, 18:23:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:31 INFO - 'ts_paint', 18:23:31 INFO - 'tpaint', 18:23:31 INFO - 'sessionrestore', 18:23:31 INFO - 'sessionrestore_no_auto_restore', 18:23:31 INFO - 'tabpaint']}, 18:23:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:31 INFO - 'ts_paint', 18:23:31 INFO - 'tpaint', 18:23:31 INFO - 'sessionrestore', 18:23:31 INFO - 'sessionrestore_no_auto_restore', 18:23:31 INFO - 'tabpaint']}, 18:23:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['a11yr', 18:23:31 INFO - 'ts_paint', 18:23:31 INFO - 'tpaint', 18:23:31 INFO - 'sessionrestore', 18:23:31 INFO - 'sessionrestore_no_auto_restore', 18:23:31 INFO - 'tabpaint']}, 18:23:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['a11yr', 18:23:31 INFO - 'ts_paint', 18:23:31 INFO - 'tpaint', 18:23:31 INFO - 'sessionrestore', 18:23:31 INFO - 'sessionrestore_no_auto_restore', 18:23:31 INFO - 'tabpaint']}, 18:23:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['tsvgx', 18:23:31 INFO - 'tsvgr_opacity', 18:23:31 INFO - 'tart', 18:23:31 INFO - 'tscrollx', 18:23:31 INFO - 'cart', 18:23:31 INFO - 'tsvg_static']}, 18:23:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:31 INFO - 'tsvgr_opacity', 18:23:31 INFO - 'tart', 18:23:31 INFO - 'tscrollx', 18:23:31 INFO - 'cart', 18:23:31 INFO - 'tsvg_static']}, 18:23:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'talos_options': ['--disable-e10s'], 18:23:31 INFO - 'tests': ['tp5o']}, 18:23:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:23:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'talos_options': ['--disable-e10s', 18:23:31 INFO - '--xperf_path', 18:23:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:31 INFO - 'tests': ['tp5n']}, 18:23:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:23:31 INFO - 'talos_options': ['--xperf_path', 18:23:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:31 INFO - 'tests': ['tp5n']}}, 18:23:31 INFO - 'talos.zip': {'path': '', 18:23:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:31 INFO - Running post-action listener: _resource_record_post_action 18:23:31 INFO - [mozharness: 2017-04-05 01:23:31.784372Z] Finished populate-webroot step (success) 18:23:31 INFO - [mozharness: 2017-04-05 01:23:31.784508Z] Running create-virtualenv step. 18:23:31 INFO - Running pre-action listener: _resource_record_pre_action 18:23:31 INFO - Running main action method: create_virtualenv 18:23:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23:31 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 18:23:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:31 INFO - Using real prefix '/tools/python27' 18:23:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:35 INFO - Installing pip.................done. 18:23:35 INFO - Return code: 0 18:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:23:35 INFO - Reading from file tmpfile_stdout 18:23:35 INFO - Output received: 18:23:35 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) 18:23:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:23:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:35 INFO - 'HOME': '/Users/cltbld', 18:23:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:35 INFO - 'LOGNAME': 'cltbld', 18:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:35 INFO - 'MOZ_NO_REMOTE': '1', 18:23:35 INFO - 'NO_EM_RESTART': '1', 18:23:35 INFO - 'PAGER': '/bin/cat', 18:23:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:35 INFO - 'PWD': '/builds/slave/test', 18:23:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:35 INFO - 'SHELL': '/bin/bash', 18:23:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:35 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:35 INFO - 'USER': 'cltbld', 18:23:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:35 INFO - 'XPC_FLAGS': '0x0', 18:23:35 INFO - 'XPC_SERVICE_NAME': '0', 18:23:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:36 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 18:23:36 INFO - Cleaning up... 18:23:36 INFO - Return code: 0 18:23:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23:36 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')]} 18:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:36 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')]} 18:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:36 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:36 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 18:23:36 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 18:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:36 INFO - 'HOME': '/Users/cltbld', 18:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:36 INFO - 'LOGNAME': 'cltbld', 18:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:36 INFO - 'MOZ_NO_REMOTE': '1', 18:23:36 INFO - 'NO_EM_RESTART': '1', 18:23:36 INFO - 'PAGER': '/bin/cat', 18:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:36 INFO - 'PWD': '/builds/slave/test', 18:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:36 INFO - 'SHELL': '/bin/bash', 18:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:36 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:36 INFO - 'USER': 'cltbld', 18:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:36 INFO - 'XPC_FLAGS': '0x0', 18:23:36 INFO - 'XPC_SERVICE_NAME': '0', 18:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:36 INFO - Downloading/unpacking psutil>=3.1.1 18:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:23:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:40 INFO - Installing collected packages: psutil 18:23:40 INFO - Running setup.py install for psutil 18:23:40 INFO - building 'psutil._psutil_osx' extension 18:23:40 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 18:23:41 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 18:23:41 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 18:23:41 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 18:23:41 INFO - building 'psutil._psutil_posix' extension 18:23:41 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 18:23:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:23:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:23:41 INFO - ^ 18:23:41 INFO - 1 warning generated. 18:23:41 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 18:23:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:41 INFO - Successfully installed psutil 18:23:41 INFO - Cleaning up... 18:23:41 INFO - Return code: 0 18:23:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:41 INFO - 'HOME': '/Users/cltbld', 18:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:41 INFO - 'LOGNAME': 'cltbld', 18:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:41 INFO - 'MOZ_NO_REMOTE': '1', 18:23:41 INFO - 'NO_EM_RESTART': '1', 18:23:41 INFO - 'PAGER': '/bin/cat', 18:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:41 INFO - 'PWD': '/builds/slave/test', 18:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:41 INFO - 'SHELL': '/bin/bash', 18:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:41 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:41 INFO - 'USER': 'cltbld', 18:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:41 INFO - 'XPC_FLAGS': '0x0', 18:23:41 INFO - 'XPC_SERVICE_NAME': '0', 18:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:23:41 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 18:23:41 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 18:23:41 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 18:23:41 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 18:23:41 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 18:23:41 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 18:23:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:23:44 INFO - Installing collected packages: mozsystemmonitor 18:23:44 INFO - Running setup.py install for mozsystemmonitor 18:23:44 INFO - Successfully installed mozsystemmonitor 18:23:44 INFO - Cleaning up... 18:23:44 INFO - Return code: 0 18:23:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:23:44 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')]} 18:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:44 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')]} 18:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:44 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:44 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 18:23:44 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 18:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:44 INFO - 'HOME': '/Users/cltbld', 18:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:44 INFO - 'LOGNAME': 'cltbld', 18:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:44 INFO - 'MOZ_NO_REMOTE': '1', 18:23:44 INFO - 'NO_EM_RESTART': '1', 18:23:44 INFO - 'PAGER': '/bin/cat', 18:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:44 INFO - 'PWD': '/builds/slave/test', 18:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:44 INFO - 'SHELL': '/bin/bash', 18:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:44 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:44 INFO - 'USER': 'cltbld', 18:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:44 INFO - 'XPC_FLAGS': '0x0', 18:23:44 INFO - 'XPC_SERVICE_NAME': '0', 18:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:45 INFO - Downloading/unpacking jsonschema==2.5.1 18:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:48 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:23:48 INFO - warning: no files found matching '*.txt' 18:23:48 INFO - no previously-included directories found matching 'build' 18:23:48 INFO - no previously-included directories found matching 'dist' 18:23:48 INFO - no previously-included directories found matching '.git*' 18:23:48 INFO - Installing collected packages: jsonschema, functools32 18:23:48 INFO - Running setup.py install for functools32 18:23:48 INFO - warning: no files found matching '*.txt' 18:23:48 INFO - no previously-included directories found matching 'build' 18:23:48 INFO - no previously-included directories found matching 'dist' 18:23:48 INFO - no previously-included directories found matching '.git*' 18:23:48 INFO - Successfully installed jsonschema functools32 18:23:48 INFO - Cleaning up... 18:23:48 INFO - Return code: 0 18:23:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:23:48 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')]} 18:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:48 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')]} 18:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:48 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:48 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 18:23:48 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 18:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:48 INFO - 'HOME': '/Users/cltbld', 18:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:48 INFO - 'LOGNAME': 'cltbld', 18:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:48 INFO - 'MOZ_NO_REMOTE': '1', 18:23:48 INFO - 'NO_EM_RESTART': '1', 18:23:48 INFO - 'PAGER': '/bin/cat', 18:23:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:48 INFO - 'PWD': '/builds/slave/test', 18:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:48 INFO - 'SHELL': '/bin/bash', 18:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:48 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:48 INFO - 'USER': 'cltbld', 18:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:48 INFO - 'XPC_FLAGS': '0x0', 18:23:48 INFO - 'XPC_SERVICE_NAME': '0', 18:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:23:48 INFO - Cleaning up... 18:23:48 INFO - Return code: 0 18:23:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:23:48 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')]} 18:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:48 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')]} 18:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:48 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:48 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 18:23:48 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 18:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:48 INFO - 'HOME': '/Users/cltbld', 18:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:48 INFO - 'LOGNAME': 'cltbld', 18:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:48 INFO - 'MOZ_NO_REMOTE': '1', 18:23:48 INFO - 'NO_EM_RESTART': '1', 18:23:48 INFO - 'PAGER': '/bin/cat', 18:23:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:48 INFO - 'PWD': '/builds/slave/test', 18:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:48 INFO - 'SHELL': '/bin/bash', 18:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:48 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:48 INFO - 'USER': 'cltbld', 18:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:48 INFO - 'XPC_FLAGS': '0x0', 18:23:48 INFO - 'XPC_SERVICE_NAME': '0', 18:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:48 INFO - Downloading/unpacking blobuploader==1.2.4 18:23:48 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 18:23:48 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 18:23:48 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 18:23:48 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 18:23:48 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 18:23:48 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 18:23:51 INFO - Downloading blobuploader-1.2.4.tar.gz 18:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:23:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:23:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:23:52 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 18:23:52 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 18:23:52 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 18:23:52 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 18:23:52 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 18:23:52 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 18:23:52 INFO - Downloading docopt-0.6.1.tar.gz 18:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:23:52 INFO - Installing collected packages: blobuploader, requests, docopt 18:23:52 INFO - Running setup.py install for blobuploader 18:23:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:23:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:23:53 INFO - Running setup.py install for requests 18:23:53 INFO - Running setup.py install for docopt 18:23:53 INFO - Successfully installed blobuploader requests docopt 18:23:53 INFO - Cleaning up... 18:23:53 INFO - Return code: 0 18:23:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:53 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')]} 18:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:53 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')]} 18:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:53 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:53 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 18:23:53 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 18:23:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:53 INFO - 'HOME': '/Users/cltbld', 18:23:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:53 INFO - 'LOGNAME': 'cltbld', 18:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:53 INFO - 'MOZ_NO_REMOTE': '1', 18:23:53 INFO - 'NO_EM_RESTART': '1', 18:23:53 INFO - 'PAGER': '/bin/cat', 18:23:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:53 INFO - 'PWD': '/builds/slave/test', 18:23:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:53 INFO - 'SHELL': '/bin/bash', 18:23:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:53 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:53 INFO - 'USER': 'cltbld', 18:23:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:53 INFO - 'XPC_FLAGS': '0x0', 18:23:53 INFO - 'XPC_SERVICE_NAME': '0', 18:23:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-Zs4GZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-VL9mY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-UOP6hU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-A6FZMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-ISaBrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-zuHv3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-KzAzos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-jZb4Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:54 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-g7dv7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-A2cnMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-IOO71s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-6ePjJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-dkwwce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-9sT7eA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-vGPa64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-5ltsPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-IWz_4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:23:55 INFO - Running setup.py install for manifestparser 18:23:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:23:56 INFO - Running setup.py install for mozcrash 18:23:56 INFO - Running setup.py install for mozdebug 18:23:56 INFO - Running setup.py install for mozdevice 18:23:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:23:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:23:56 INFO - Running setup.py install for mozfile 18:23:56 INFO - Running setup.py install for mozhttpd 18:23:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:23:56 INFO - Running setup.py install for mozinfo 18:23:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:23:56 INFO - Running setup.py install for mozInstall 18:23:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Running setup.py install for mozleak 18:23:57 INFO - Running setup.py install for mozlog 18:23:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Running setup.py install for moznetwork 18:23:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Running setup.py install for mozprocess 18:23:57 INFO - Running setup.py install for mozprofile 18:23:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:23:57 INFO - Running setup.py install for mozrunner 18:23:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:23:58 INFO - Running setup.py install for mozscreenshot 18:23:58 INFO - Running setup.py install for moztest 18:23:58 INFO - Running setup.py install for mozversion 18:23:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:23:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:23:58 INFO - Cleaning up... 18:23:58 INFO - Return code: 0 18:23:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:58 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')]} 18:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:58 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')]} 18:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:58 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:23:58 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 18:23:58 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 18:23:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:23:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:58 INFO - 'HOME': '/Users/cltbld', 18:23:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:58 INFO - 'LOGNAME': 'cltbld', 18:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:58 INFO - 'MOZ_NO_REMOTE': '1', 18:23:58 INFO - 'NO_EM_RESTART': '1', 18:23:58 INFO - 'PAGER': '/bin/cat', 18:23:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:58 INFO - 'PWD': '/builds/slave/test', 18:23:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:58 INFO - 'SHELL': '/bin/bash', 18:23:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:23:58 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:23:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:58 INFO - 'USER': 'cltbld', 18:23:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:58 INFO - 'XPC_FLAGS': '0x0', 18:23:58 INFO - 'XPC_SERVICE_NAME': '0', 18:23:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:58 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-caq1oX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:58 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)) 18:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:58 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-3s9RY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:58 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)) 18:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:58 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-tRPQ0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-XFHKko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-BEh8mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-EqEWWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-NctWBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-i8CWTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-4vTEDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-RZ5cOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-KlleA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:59 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)) 18:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-6sokr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-Ww1Oo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:00 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)) 18:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-xiQqMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:00 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)) 18:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-oUER6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:00 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)) 18:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-RwwnIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:00 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)) 18:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-lLLuID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:00 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)) 18:24:00 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)) 18:24:00 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)) 18:24:00 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)) 18:24:00 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)) 18:24:00 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)) 18:24:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:00 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 18:24:00 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 18:24:00 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 18:24:00 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 18:24:00 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 18:24:00 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 18:24:03 INFO - Downloading blessings-1.6.tar.gz 18:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:03 INFO - Installing collected packages: blessings 18:24:03 INFO - Running setup.py install for blessings 18:24:03 INFO - Successfully installed blessings 18:24:03 INFO - Cleaning up... 18:24:03 INFO - Return code: 0 18:24:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:03 INFO - Reading from file tmpfile_stdout 18:24:03 INFO - Current package versions: 18:24:03 INFO - blessings == 1.6 18:24:03 INFO - blobuploader == 1.2.4 18:24:03 INFO - docopt == 0.6.1 18:24:03 INFO - functools32 == 3.2.3-2 18:24:03 INFO - jsonschema == 2.5.1 18:24:03 INFO - manifestparser == 1.1 18:24:03 INFO - mozInstall == 1.12 18:24:03 INFO - mozcrash == 1.0 18:24:03 INFO - mozdebug == 0.1 18:24:03 INFO - mozdevice == 0.49 18:24:03 INFO - mozfile == 1.2 18:24:03 INFO - mozhttpd == 0.7 18:24:03 INFO - mozinfo == 0.9 18:24:03 INFO - mozleak == 0.1 18:24:03 INFO - mozlog == 3.4 18:24:03 INFO - moznetwork == 0.27 18:24:03 INFO - mozprocess == 0.25 18:24:03 INFO - mozprofile == 0.28 18:24:03 INFO - mozrunner == 6.13 18:24:03 INFO - mozscreenshot == 0.1 18:24:03 INFO - mozsystemmonitor == 0.3 18:24:03 INFO - moztest == 0.8 18:24:03 INFO - mozversion == 1.4 18:24:03 INFO - psutil == 3.1.1 18:24:03 INFO - requests == 1.2.3 18:24:03 INFO - wsgiref == 0.1.2 18:24:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:24:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:24:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:03 INFO - 'HOME': '/Users/cltbld', 18:24:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:03 INFO - 'LOGNAME': 'cltbld', 18:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:03 INFO - 'MOZ_NO_REMOTE': '1', 18:24:03 INFO - 'NO_EM_RESTART': '1', 18:24:03 INFO - 'PAGER': '/bin/cat', 18:24:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:03 INFO - 'PWD': '/builds/slave/test', 18:24:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:03 INFO - 'SHELL': '/bin/bash', 18:24:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:24:03 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:24:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:03 INFO - 'USER': 'cltbld', 18:24:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:03 INFO - 'XPC_FLAGS': '0x0', 18:24:03 INFO - 'XPC_SERVICE_NAME': '0', 18:24:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 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)) 18:24:04 INFO - Cleaning up... 18:24:04 INFO - Return code: 0 18:24:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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')]} 18:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:04 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 0x101c17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b42da3450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, '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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:24:04 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 18:24:04 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 18:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:04 INFO - 'HOME': '/Users/cltbld', 18:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:04 INFO - 'LOGNAME': 'cltbld', 18:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:04 INFO - 'MOZ_NO_REMOTE': '1', 18:24:04 INFO - 'NO_EM_RESTART': '1', 18:24:04 INFO - 'PAGER': '/bin/cat', 18:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:04 INFO - 'PWD': '/builds/slave/test', 18:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:04 INFO - 'SHELL': '/bin/bash', 18:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:24:04 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:04 INFO - 'USER': 'cltbld', 18:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:04 INFO - 'XPC_FLAGS': '0x0', 18:24:04 INFO - 'XPC_SERVICE_NAME': '0', 18:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:04 INFO - Cleaning up... 18:24:04 INFO - Return code: 0 18:24:04 INFO - Running post-action listener: _resource_record_post_action 18:24:04 INFO - Running post-action listener: _start_resource_monitoring 18:24:04 INFO - Starting resource monitoring. 18:24:04 INFO - [mozharness: 2017-04-05 01:24:04.378234Z] Finished create-virtualenv step (success) 18:24:04 INFO - [mozharness: 2017-04-05 01:24:04.379209Z] Running install step. 18:24:04 INFO - Running pre-action listener: _resource_record_pre_action 18:24:04 INFO - Running main action method: install 18:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:04 INFO - Reading from file tmpfile_stdout 18:24:04 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:04 INFO - Reading from file tmpfile_stdout 18:24:04 INFO - Output received: 18:24:04 INFO - Usage: mozinstall [options] installer 18:24:04 INFO - Options: 18:24:04 INFO - -h, --help show this help message and exit 18:24:04 INFO - -d DEST, --destination=DEST 18:24:04 INFO - Directory to install application into. [default: 18:24:04 INFO - "/builds/slave/test"] 18:24:04 INFO - --app=APP Application being installed. [default: firefox] 18:24:04 INFO - mkdir: /builds/slave/test/build/application 18:24:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:24:25 INFO - Reading from file tmpfile_stdout 18:24:25 INFO - Output received: 18:24:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:24:25 INFO - Running post-action listener: _resource_record_post_action 18:24:25 INFO - [mozharness: 2017-04-05 01:24:25.054650Z] Finished install step (success) 18:24:25 INFO - [mozharness: 2017-04-05 01:24:25.054789Z] Running run-tests step. 18:24:25 INFO - Running pre-action listener: _resource_record_pre_action 18:24:25 INFO - Running main action method: run_tests 18:24:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:24:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:24:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:24:25 INFO - 2017-04-04 18:24:25.069 screenresolution[1636:8611] starting screenresolution argv=screenresolution get 18:24:25 INFO - 2017-04-04 18:24:25.072 screenresolution[1636:8611] Display 0: 1600x1200x32@60 18:24:25 INFO - 2017-04-04 18:24:25.078 screenresolution[1637:8614] starting screenresolution argv=screenresolution list 18:24:25 INFO - Available Modes on Display 0 18:24:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:24:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:24:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:24:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:24:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:24:25 INFO - Intel Iris: 18:24:25 INFO - Chipset Model: Intel Iris 18:24:25 INFO - Type: GPU 18:24:25 INFO - Bus: Built-In 18:24:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:24:25 INFO - Vendor: Intel (0x8086) 18:24:25 INFO - Device ID: 0x0a2e 18:24:25 INFO - Revision ID: 0x0009 18:24:25 INFO - Displays: 18:24:25 INFO - Display: 18:24:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:24:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:24:25 INFO - Display Serial Number: 1600x1200 60 18:24:25 INFO - Main Display: Yes 18:24:25 INFO - Mirror: Off 18:24:25 INFO - Online: Yes 18:24:25 INFO - Rotation: Supported 18:24:25 INFO - Return code: 0 18:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:24:25 INFO - Python 2.7.3 18:24:25 INFO - Return code: 0 18:24:25 INFO - grabbing minidump binary from tooltool 18:24: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')]} 18:24:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5d30>, '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 0x10191cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019eabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:24:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:24:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:24:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:24:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:24:25 INFO - Return code: 0 18:24:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:24:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:24:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:24:25 INFO - ENV: RUST_BACKTRACE is now 1 18:24:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:24:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 18:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0316 --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 18:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kQIQrDBMd2/Render', 18:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:25 INFO - 'HOME': '/Users/cltbld', 18:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:25 INFO - 'LOGNAME': 'cltbld', 18:24:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:24:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:25 INFO - 'MOZ_NO_REMOTE': '1', 18:24:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:24:25 INFO - 'NO_EM_RESTART': '1', 18:24:25 INFO - 'PAGER': '/bin/cat', 18:24:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:25 INFO - 'PWD': '/builds/slave/test', 18:24:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:25 INFO - 'RUST_BACKTRACE': '1', 18:24:25 INFO - 'SHELL': '/bin/bash', 18:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.g4xhqUGxMy/Listeners', 18:24:25 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:25 INFO - 'USER': 'cltbld', 18:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:25 INFO - 'XPC_FLAGS': '0x0', 18:24:25 INFO - 'XPC_SERVICE_NAME': '0', 18:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 18:24:25 INFO - mozversion application_buildid: 20170404174627 18:24:25 INFO - mozversion application_changeset: 896e9cfb9d67d6a73e70e39532f31306c22202cb 18:24:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:24:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:24:25 INFO - mozversion application_name: Firefox 18:24:25 INFO - mozversion application_remotingname: firefox-dev 18:24:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:24:25 INFO - mozversion application_vendor: Mozilla 18:24:25 INFO - mozversion application_version: 54.0a2 18:24:25 INFO - mozversion platform_buildid: 20170404174627 18:24:25 INFO - mozversion platform_changeset: 896e9cfb9d67d6a73e70e39532f31306c22202cb 18:24:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:24:25 INFO - mozversion platform_version: 54.0a2 18:24:25 INFO - using testdate: 1491355465 18:24:25 INFO - actual date: 1491355465 18:24:25 INFO - starting webserver on 'localhost:49239' 18:24:25 INFO - SUITE-START | Running 2 tests 18:24:25 INFO - TEST-START | dromaeo_css 18:24:25 INFO - operating with platform_type : mac_ 18:24:25 INFO - Initialising browser for dromaeo_css test... 18:24:25 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmptoz1w8/profile http://localhost:49239/getInfo.html) 18:24:28 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:24:28 INFO - PROCESS | 1647 | colorDepth:24 18:24:28 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:24:28 INFO - PROCESS | 1647 | __metrics 18:24:28 ERROR - PROCESS | 1647 | 1491355468240 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4954:11 < observe@XPIProvider.jsm:2917:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:24:28 INFO - PROCESS | 1647 | 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" 18:24:28 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:24:29 INFO - TEST-INFO | 1647: exit 0 18:24:29 INFO - Browser initialized. 18:24:29 INFO - Running cycle 1/1 for dromaeo_css test... 18:24:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmptoz1w8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:41 INFO - PROCESS | 1650 | RSS: Main: 215273472 18:24:41 INFO - PROCESS | 1650 | 18:27:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 18:27:48 INFO - PROCESS | 1650 | RSS: Main: 420118528 18:27:48 INFO - PROCESS | 1650 | 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 18:29:30 INFO - PROCESS | 1650 | 18:29:30 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:29:30 INFO - PROCESS | 1650 | Full stack: 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 18:29:30 INFO - PROCESS | 1650 | 18:29:30 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:29:30 INFO - PROCESS | 1650 | Full stack: 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 18:29:30 INFO - PROCESS | 1650 | 18:29:30 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:29:30 INFO - PROCESS | 1650 | Full stack: 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | ************************* 18:29:30 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 18:29:30 INFO - PROCESS | 1650 | 18:29:30 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:29:30 INFO - PROCESS | 1650 | Full stack: 18:29:30 INFO - PROCESS | 1650 | ************************* 18:30:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 18:30:51 INFO - PROCESS | 1650 | RSS: Main: 350941184 18:30:51 INFO - PROCESS | 1650 | 18:33:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 18:33:54 INFO - PROCESS | 1650 | RSS: Main: 339914752 18:33:54 INFO - PROCESS | 1650 | 18:36:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 18:36:58 INFO - PROCESS | 1650 | RSS: Main: 326758400 18:36:58 INFO - PROCESS | 1650 | 18:40:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 18:40:02 INFO - PROCESS | 1650 | RSS: Main: 325431296 18:40:02 INFO - PROCESS | 1650 | 18:43:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 18:43:06 INFO - PROCESS | 1650 | RSS: Main: 467881984 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | __start_tp_report 18:43:06 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 18:43:06 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 18:43:06 INFO - PROCESS | 1650 | |i|pagename|runs| 18:43:06 INFO - PROCESS | 1650 | |0;dojo.html;2932.6187595612573;3318.0543545089654;3355.479900615404;3368.174797174692;3380.729541636671;5994.940050599493;6007.3692262312425;6011.188489553909;6034.456898879105;6059.394060593939;5966.5226781857455;6039.758409663619;6056.788012419566;6058.606190597619;6061.393860613936;5347.144456886899;5394.083005889008;5402.648827826199;5450.863728406782;5486.231927530129;5279.788808447676;5322.04203243416;5372.892542149155;5496.835094947158;5530.949119667251;5389.83830485086;5411.945880541203;5417.51242388185;5417.6207665463435;5418.431064738185;5380.488853558905;5394.541463975567;5399.919001214966;5402.297701298845;5402.945970540283;5247.86880327991;5353.9732301338245;5354.196870469461;5361.169018802092;5381.300430943991;6003.159557661931;6004.489618382399;6013.278406591194;6017.969910150465;6027.457528822383;6150.831342044997;6206.038064100072;6217.906731399033;6220.440160385542;6229.781957631507;5254.8686282842855;6207.844803879939;6254.718537665816;6293.685315734195;6337.429631333202;6000.568065736175;6068.57519973602;6071.908921366183;6077.1491991121275;6137.693115344259;6202.976508876075;6469.191351081115;6486.254080780724;6509.2839787623325;6602.8349291268105;6233;6252.499800015988;6265.592736472115;6269.811905642747;6307.211598550181;6319.968400158062;6358.809235722936;6368.554201205871;6375.171227740457;6400.583962042452;6292.182016337938;6299.527535434769;6299.748010079637;6310.305866354697;6327.905081423783;6170;6258.311585725566;6310.4636105930595;6313.526485513605;6327.525435592349;5155.407128180233;5163.8192663256605;5190.065788158167;5193.350831146106;5230.293910322134;4533.637309015271;4546.090781843644;4555.589996900295;4558.5897269245925;4582.656300777455;5743.396943320975;5999.190109335271;6045.486133678686;6046.576739628272;6079.088136779518;6220.813375598739;6253.874922501524;6287.685615719196;6291.465225455888;6302.810915672446;5264.684118952799;6011.158437818709;6028.57799953999;6028.849278768066;6045.576809623372;5643.294588176478;5650.830475085754;5654.067078932012;5675.233843431167;5678.14827775837;6156.969215153896;6312.6528040956355;6330.39861213184;6446.4520515756685;6577.506686998398;6322.557420980487;6399.55203135776;6407.878621241357;6408.00675895237;6414.93585064162;6597.340265973365;6608.107905432705;6608.669566521558;6609.933900660932;6621.6689165540565;6173.969130154321;6233.906491402723;6301.779437719658;6337.90493142594;6381.617102973836;6245.437910588069;6254.718537665725;6262.279837818527;6352.078948552342;6356.682165891594;4914.508549145057;5217.34783152106;5376.892462150813;6166.630002199882;6240.843978900564;6224.2842073160355;6228;6280.089387038763;6296.5592408530965;6306.653134077486;6030.547708921761;6047.455728984412;6109.8298075392895;6126.448619624255;6139.308154112039;6236.501079913687;6275.152854364785;6279.089532017923;6287.46556542699;6303.149074874833;6036.124761909587;6075.149479072845;6122.215193971364;6140.631562106288;6187.288461826767 18:43:06 INFO - PROCESS | 1650 | |1;ext.html;791.398537111795;809.4414853750911;876.2945828607972;885.4952676974127;887.4453466583385;34730.13174670631;40816.387754183714;41565.792171039175;41601.58398416015;41632.16735665285;40676.59323406765;40893.59106408935;40971;40972.59027409725;41050.76847694567;7420.814479637998;7564.810879727996;9208.95395523023;9359.812803743922;9394.530273486333;11018.724531886726;11187.105031597453;11329.716757081098;11369.602063927765;11371.715707107347;9762.414255144678;11159.665210043711;11189.992900638941;11193.048590869786;11232.719182020433;7216.639168041629;7381.077365329334;9700.660476883275;11119.277246978962;11165.497552610152;10723.410212438313;10954.068904143158;11071.778564428667;11075.833862491989;11085.72285692856;38258.617413825785;38510.61489385098;38530;38543.03642408962;38573.42139867904;3739.4951681522916;3864.381698928158;3909.902252443683;3911.2764138634443;3946.112124771932;23635.29094127179;24157.637635435305;24819.87590062038;24899.5020099597;26605.733942660714;3032.8938487152845;3071.4932036214;3133.6239651241767;3149;3232.7252183564424;365047.1747641271;367422;367458.9999999973;367987;368298;44774.32838507425;44862;45025.099498009855;45065.09869802585;45086.549134508234;47194.5280547197;47368;47378.76310618425;47399.76300118477;47420.5257947423;12494;12568.874311256954;12618.684532886567;13723.862761372458;13725.382357793924;11220.046296064926;11339.036181924628;11679.416029198506;11709.824352634718;11713.121515886343;10548;10978.341299522055;11829.704257393461;12077.939610302068;12177.634670959886;5558.722063896789;5665.66006039639;5680.971595141998;5689.800856969986;5706.229658996065;1047.7694907120422;1050.4065203160342;1072.3137192197;1081.4106312059946;1111.127764704703;27707.03025394142;27962.396805321856;28486.002989895253;28504.287392815346;28557.57163642547;1032.230987914009;1061.1722856172198;1107.4960892793758;1123.4888125902694;1143.7998350288667;14101.78847317291;14115.576532704035;14115.576532704035;14414.783778243334;14425.567232982818;504139;506576;507328;508786;508966;56576;56822;57115.71442142763;57274.41804655617;57293;1255.5166260989586;1279.4434421026883;1438.604383794444;1463.6340914771308;1474.3144437836243;19973.700394493804;30267.94062207812;30382.088537343916;31041;31067.533986990664;41240.58759412367;51640.48359516507;51642.48357516527;51689.224661629356;51815.74092129515;443843;454728;455943;456278;456360;31346.373072538878;31766;31971.520427194075;31983.68016319807;32122.03633890987;38003.239935201695;44474;44612.55387446084;44971.32543011923;45151.77424112859;28963.420731585673;30169.37967443921;30873;30889.382212356075;31185;29861.10416687503;30871.691283086882;30873.691263087083;30953.380932380776;31039;35753.64246357503;36309.09227269234;36397.636023639425;36749.44875826918;36837.81581092077;30133.246668833453;30568.388632227674;31247;31310.686893130776;31410.52884206693;23184.652230216223;24351.51296974015;24361.51276974486;24735.62896556517;24791.628125578492 18:43:06 INFO - PROCESS | 1650 | |2;jquery.html;385.3756913799645;403.9838406463742;418.6274215947806;426.26043813982744;448.49992258631636;13452.663683407907;20473.484848484833;21672.566548669038;22180.223692170737;22502.212422565215;22030.669539956914;22502.437439064033;23145.884270578623;23146.30561083166;23220.767792322073;5603.739158689283;6711.563748356353;6853.520253582251;6863.1764188297475;6908.412784913288;6789.830254243634;6823.7611683591085;6877.931220687779;6890.655467226644;6895.275996020421;6506.414422701956;6709.463242940553;6768.593884366929;6772.796816095525;6783.287754785751;6745.426638735713;6775.424088952445;6776.898346524806;6779.49153813466;6899.275576064541;6512.12086368339;6704.731810727614;6715.731370745164;6718;6726;26621.600675989877;26644.73355266442;27018;27155.049573265038;27698;2614.542455070355;2934.5598160275918;2936.4420760055523;2944.823310601371;2978.1214541710247;15910.204489775464;16482.75275870863;16509.66980660392;16649;17161.227744751515;1816.2825683854803;1995.311617491963;2044.7137400763904;2053.887036213008;2056.074766355131;131392.34303828515;132555;139823;139865.300673497;141967.29016354957;15277.923610381877;21358.893205534183;21507.14742053996;21551.67672484914;21842.924922132373;32904.177395565304;33267.00198994034;33407.997760067236;34372.82813585916;34717.82641086778;7969.203079692101;8439.143388454653;10838.132949364031;10970;11019.449027548591;8766.21104100634;8955.686550970684;8977.23693486061;9057.592408341641;9076.954615227014;10305.484725763681;10442;10443.216758743125;10547.519318624836;10557.94420557938;4046.4132700758223;4110.125973078755;4133.483314585676;4134.945588874878;4135.152293779768;986.358866736627;1079.63292480557;1085.9674209773718;1106.253279036647;1153.3541216918552;20577.69133462999;22926.42683932915;23249.651255231183;23282.883585581963;23322.416939576648;982.0523195116747;996.267743208741;1131.6095946898306;1135.6195674448986;1158.206628459508;10900;11304.830427543593;11312.095032397388;12198.560287942448;12654.114212005072;731694;742987;743656.281718588;756588;758328;119810;122919.77080229262;135279;140119;148047;1252.1610520950985;1261.6845788552862;1287.304855378104;1289.6840274132896;1294.3204817470903;19064.937645948117;20165.890876001373;20224.494387640425;20340.694889576964;20388;63114.383966096924;64195.99999999813;64799;64992.35007649863;66201.66899165473;88974.66538001805;89371.55314223387;91038.54480727554;92079;92141.61787573325;11381;11464.541418343173;11650.242734222249;11888.108391870472;11997.160198786001;12988.48046078184;13024.283664398548;13058.869411305765;13300;13307.401166947317;11071.391073491035;11149.498272577915;11169.050630696482;11337.319760814378;11402.885971140182;9989.450580218157;10551.894481055399;10676.89323106759;10727.356358618508;10858.619948302088;11533.769324613628;11569.826452603385;11753.764924701629;11769.941150294193;11845.644630661094;11273.605423810128;11453.885461145283;11790.05464098414;11799;11940.820887686865;11256.437178141225;11395.202335836411;11630.94184529072;11653.00949419275;11688.123390745557 18:43:06 INFO - PROCESS | 1650 | |3;mootools.html;1544.7373946429107;1560.5240401677497;1570.7486802111657;1594.1550977981667;1595.9202039898003;10480.58077676893;10995.065419439357;11005.06456951159;11018.834717479223;11033.503492342843;10635.57457701691;11057.336559806417;11168.609098681547;11170.994610485055;11196.944015279912;2392.6650268962358;2420.9273721788372;2430.1130087518104;2431.8297719159664;2433.537627850703;2325;2387.3673476528734;2419.588669926117;2421.3946513371657;2432.3067925641194;2341.0401735288533;2388.044782087162;2402.939926501843;2429.7327293997646;2435.5007223519137;2340.8010319122895;2391.80865530758;2394;2418.069043418279;2419.8548087114827;2095.758987716417;2349.988250058756;2383.2611890314056;2385.2009576791797;2389.522095580891;10925;11151.163662725328;11158.553657853676;11163.83254251187;11169.329840209613;5664.518515926151;5816.1275808628625;5842.6202296851;5882.529397648178;5900.527957763368;3497.300539892032;3534.9116272093147;3618.565772107364;3626.075350785533;3653.725970552219;1115.5482029777952;1121.0471099565304;1129.6780417581033;1234.796258617327;1261.703499677575;13523.864761352359;13861.376238069312;14135.929320353538;15218.619534511616;15417.22913854314;4636.35091087248;4802.183628783046;4810.278458231293;4827.2035114206465;4853.39332583427;10146.695599132037;11738.882611173778;11773.17587768865;11788.115891308185;11788.997935175435;4928.0390323886495;5579.107342825128;5740.13897915316;5811.4479124483105;5851.64890106595;5130.87172820675;5234.7906083756225;5240.633155679066;5326.201069839478;5335.493128152819;3915.882523524299;3932.9016774580864;3978.1446988896946;4000.6799456043445;4006.2988976928923;919.9218066464292;1044.770150566874;1047.4657924458581;1060.6181774561153;1065.0627447845845;899.5142622983516;918.5820451694462;924.5700749151542;927.4296307770672;961.8412961861354;6350.809475715736;6358.491320694333;6391.456726178327;6435.839104022437;6503.544751867418;944.1078181118914;953.4851180362526;962.100436092256;967.5500892085214;972.4797233480195;4068.7762173080173;4186.937195942063;4208.494980602347;4300.8709738707885;4347.369631403492;13028.47886084565;13633.386497607631;13649.727005459836;13656.93171534136;13716;12712.173708708904;13062.477500900048;13257.270850103145;13506;13548.322583870768;9894.06006429388;9990.80018399628;10054;10127.594896204217;10153.695389138338;6350.618962862243;6407.71165297572;6485.254195767548;6523.18460192476;6576.967115164393;10869.29349592274;11619.941900290445;12042.939785301018;12150;12217.266963982189;10118.747031324276;11043.72390690207;11441.913018263253;11756.23584467007;11760.411979400824;9046.321525885453;9106.408083474553;9233.630654773733;9298.977112517752;9389.483578403253;8322.62548185321;10090.596376144871;10305.072543471124;10333.276670633284;10345.120664743281;8636.438631488933;8759.474431534129;8846.248068914214;8848.02671706099;8880.28957683371;8654;8692.78268043304;8749.387542871938;8762.342824288076;8782.253508451853;7805.531668099932;7953.8409231816195;7968.402369822171;7983.2415920487465;7985.2014798522005;5735.254416925773;5768.336641286138;5782.5373970081455;5938.881222375615;5957;5452.372977107683;5737.885242295214;5928.911066334094;5953.226080609493;5992.13114098462 18:43:06 INFO - PROCESS | 1650 | |4;prototype.html;363.03794943399987;370.3666729891885;372.33352299384103;375.966163045326;380.0802059017177;4439.734675617449;4840.419149702032;4896.534829191226;4948.8267910623135;4959.851204463863;4199.93700094498;4762.428508578967;4868.099401610704;4868.634852386068;4874.829380971667;3274.4106060909025;3405.7615966882327;3510.438329867222;4075.551689314173;4091.7954102294916;3412.778169418992;4068.41008053832;4078.4298045252644;4101.487314085739;4122.2579935611575;3582.874599389022;3676.3015027144756;4089;4105.938410923838;4110.527289361717;3399.932001359959;3775.2826962876966;3779.5842457329672;4077.8980525486804;4132.938005929914;3690.501782259414;3732.693557254966;3747.814357795879;3799.378428884089;3805.752626079296;3847.807609619536;4381.474223093249;4448.909012764028;4822.469528351913;4885.096257192396;4104.486939132608;4241.27898257297;4748.667593268473;4758.928616070762;4801.663883528155;4827.0209214222;4916.754162291907;5179.507946745053;5203.453637368098;5337.839864804062;3981.2435637229123;4122.3404255319;4203.453551038346;4203.717866050853;4231.788410579458;4430.136123455927;5195.350581177353;5323.707196104174;5453.509184173404;5477.808276710336;3974.5627980922077;4146.875593732193;4260.020195355024;4300.537817142187;4304.278288684552;3845.65389114981;3892.5134358205223;4194.51094861326;4320;4370.8907277317685;4240.575942405735;4272.957270427256;4357.346398040319;4433.896272609187;4512.458504979358;4116.5917938006605;4667.439907211156;5133.537981581676;5143.434222235551;5210.869728256824;1261.035307989389;1348.9882588058956;1379.0691283383676;1471.0217705225905;1485.8484674377228;950.6245033211843;995.8307087795092;996.0139461932779;1129.785340785248;1133.4616057372848;4405.722340521213;4761.262004389325;4898;4926.310316555685;4978.477259887732;4740.857774266778;5006.77334053152;5403.48666876654;5407.351117865881;5497.752601132958;4505.36924830524;4540.273556230987;4656.95343046572;4892.260334212852;4935.481774413706;2434.707835059804;2480.751924807541;2752.229375774786;2800.467911096885;2805.424887897976;5176.120059589879;5501.559875209973;5575.944240557543;5600.327960644749;5605.943940560624;4602.930956035662;5172.844814655567;5394.085099789597;5440.708371435304;5484.341878974548;4859.930815220646;5242.648601660689;5302.3637163540625;5632.436756324417;5658.207850900795;4570.977145114386;5366.865828354323;5395.974764794676;5429.484199001088;5445.264889239902;4425.756583387944;4897.681650692693;5019.598432125348;5085.703145697823;5314.502183973526;5300.840974770763;5319.122344813217;5514.448555144453;5526.640768350045;5554.416786237387;3768.719924445671;3887.1147493465082;4331.631811296075;4353.477582690098;4363.563643635611;5397.487238712316;5437.293151890229;5464.754086066216;5469.480399362054;5504.422035686195;4441.755703436342;4734.739589322483;4935.580475659609;5183.5593974512585;5184.222366645034;4626.444826620827;4715.976420117878;5215.973920130375;5241.764120614505;5314.62797604164;4229.407882896458;4422.71252368595;4746.145693775154;4814.614830813596;5118.820841270538;4426.535213802505;4566.45202575693;5009.323741294878;5227.503387178212;5273.973630131824;4678.461976872703;4826;4918.9178380756175;5084.669496482717;5101.183810590288 18:43:06 INFO - PROCESS | 1650 | |5;yui.html;734.4271468254761;738.5310327941755;774.7598244544191;824.0770337222305;826.1985873702511;17519.82480175198;18259.36092236772;18267;18273.451796446112;18316.725249121257;16927.576810579743;17024.23390947407;17055;17092.401765938193;17097.82902170978;928.7353104365258;939.3659279985982;947.7725345916965;952.4475804033679;954.4941181173989;931.580788645109;951.1867353412836;951.6954574536152;952.0289304908989;952.2715122930963;1001.4892404873494;1002.5989604158322;1002.8696269484993;1002.9448380339079;1003.0521157506159;876.3514998900831;996.0637001218831;997.9700608981742;998.2812375089924;999.3903718731568;737.6496164321958;738.2543630932743;738.6934422214774;738.9556626602421;739.2238149942539;14035.78946315806;15330.310136043905;15331.693366132615;15641.921790390976;15668.138252396113;14066;14185;14250.2162381069;14260.572182834532;14302.928485357506;11668.824967625493;11719.179657423983;11799.764004719944;11871.109666775026;11878.940605297004;3657.5976642569294;3674.2651469706166;3679.0986208379118;3831.3678243090135;3842.9423558646645;111998;114176;116526;117310.99999999914;120202.3989880045;10698.405435247609;10768.676939691823;10927.743309519348;10976.890231097586;11038.834417483744;20143.294984675467;22655.88672056629;22736.863156842424;22740.545189096127;22764.74793886319;7442.553446793209;7691.50005249657;7777.5722335270975;8033.718819841278;8044.597770111588;4369.890752731144;4378.715383500063;4407.118576284756;4413.775274307349;4440.35614835847;3486.651334866493;3594.8022858742497;3598.0105470995686;3598.2803439312243;3598.8740394086603;586.9559783016291;607.1803065861052;614.6066517428849;618.0482057631198;621.3320680268729;632.4940047961612;653.0237295243642;676.2696288008939;685.4584877946467;686.3342557719004;9747.317687761788;9988.200943924468;10083.848742268872;10254.179665626732;10406.739831504121;566.2751677852356;617.8115674719254;683.4634811672813;690.4234963805279;694.6422592364936;4610.907781844345;4809.759512024385;4881.4142302923165;4883.731394773251;4890.339804126468;90251;92431;92732.0726792737;93231.53384233035;93289;53010.73494632502;63647.72704545883;76716.61641691756;90107;95188.52405737927;68626.313736863;68645.3135468639;70344;70591.9411208822;71659;8599.418002072849;9940.807103147668;10147.188224942134;10272.126869216201;10903.29128606638;44111.897202570195;46102;47574.76212618915;48574;50888;54955.725221373636;66291;67943.98084028644;68088;68442.63114737778;13266.668333291746;13305.268210248529;13563.660908477366;13754.93122534381;13897.374618142007;14263.215523146326;14359;14646.194459304841;14687.485937992118;14959.551213463179;13057.412416441473;13062;13141.269143108282;13517.391717372939;13595.116317439335;13106.475740970254;13442.663933401744;13803.585892423243;13904.930475347559;14206.360713767699;12457.563985260835;13024.52018711367;13131.737365252451;13299.202047876772;13407.664808379868;8269.62786674589;9873.605055797974;9923.255755818487;10149.79700405973;10293.897061029593;9176.312034247703;10758.128856506659;11019.173561983043;11041.39272340029;11074.557017719524 18:43:06 INFO - PROCESS | 1650 | __end_tp_report 18:43:06 INFO - PROCESS | 1650 | __start_cc_report 18:43:06 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2360 18:43:06 INFO - PROCESS | 1650 | __end_cc_report 18:43:06 INFO - PROCESS | 1650 | __startTimestamp1491356586059__endTimestamp 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | ------- Summary: start ------- 18:43:06 INFO - PROCESS | 1650 | Number of tests: 6 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5882.55 Median:6109.83 stddev:659.30 (10.8%) stddev-sans-first:619.38 18:43:06 INFO - PROCESS | 1650 | Values: 2932.6 3318.1 3355.5 3368.2 3380.7 5994.9 6007.4 6011.2 6034.5 6059.4 5966.5 6039.8 6056.8 6058.6 6061.4 5347.1 5394.1 5402.6 5450.9 5486.2 5279.8 5322.0 5372.9 5496.8 5530.9 5389.8 5411.9 5417.5 5417.6 5418.4 5380.5 5394.5 5399.9 5402.3 5402.9 5247.9 5354.0 5354.2 5361.2 5381.3 6003.2 6004.5 6013.3 6018.0 6027.5 6150.8 6206.0 6217.9 6220.4 6229.8 5254.9 6207.8 6254.7 6293.7 6337.4 6000.6 6068.6 6071.9 6077.1 6137.7 6203.0 6469.2 6486.3 6509.3 6602.8 6233.0 6252.5 6265.6 6269.8 6307.2 6320.0 6358.8 6368.6 6375.2 6400.6 6292.2 6299.5 6299.7 6310.3 6327.9 6170.0 6258.3 6310.5 6313.5 6327.5 5155.4 5163.8 5190.1 5193.4 5230.3 4533.6 4546.1 4555.6 4558.6 4582.7 5743.4 5999.2 6045.5 6046.6 6079.1 6220.8 6253.9 6287.7 6291.5 6302.8 5264.7 6011.2 6028.6 6028.8 6045.6 5643.3 5650.8 5654.1 5675.2 5678.1 6157.0 6312.7 6330.4 6446.5 6577.5 6322.6 6399.6 6407.9 6408.0 6414.9 6597.3 6608.1 6608.7 6609.9 6621.7 6174.0 6233.9 6301.8 6337.9 6381.6 6245.4 6254.7 6262.3 6352.1 6356.7 4914.5 5217.3 5376.9 6166.6 6240.8 6224.3 6228.0 6280.1 6296.6 6306.7 6030.5 6047.5 6109.8 6126.4 6139.3 6236.5 6275.2 6279.1 6287.5 6303.1 6036.1 6075.1 6122.2 6140.6 6187.3 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:57619.90 Median:17457.50 stddev:118660.39 (679.7%) stddev-sans-first:118916.55 18:43:06 INFO - PROCESS | 1650 | Values: 791.4 809.4 876.3 885.5 887.4 34730.1 40816.4 41565.8 41601.6 41632.2 40676.6 40893.6 40971.0 40972.6 41050.8 7420.8 7564.8 9209.0 9359.8 9394.5 11018.7 11187.1 11329.7 11369.6 11371.7 9762.4 11159.7 11190.0 11193.0 11232.7 7216.6 7381.1 9700.7 11119.3 11165.5 10723.4 10954.1 11071.8 11075.8 11085.7 38258.6 38510.6 38530.0 38543.0 38573.4 3739.5 3864.4 3909.9 3911.3 3946.1 23635.3 24157.6 24819.9 24899.5 26605.7 3032.9 3071.5 3133.6 3149.0 3232.7 365047.2 367422.0 367459.0 367987.0 368298.0 44774.3 44862.0 45025.1 45065.1 45086.5 47194.5 47368.0 47378.8 47399.8 47420.5 12494.0 12568.9 12618.7 13723.9 13725.4 11220.0 11339.0 11679.4 11709.8 11713.1 10548.0 10978.3 11829.7 12077.9 12177.6 5558.7 5665.7 5681.0 5689.8 5706.2 1047.8 1050.4 1072.3 1081.4 1111.1 27707.0 27962.4 28486.0 28504.3 28557.6 1032.2 1061.2 1107.5 1123.5 1143.8 14101.8 14115.6 14115.6 14414.8 14425.6 504139.0 506576.0 507328.0 508786.0 508966.0 56576.0 56822.0 57115.7 57274.4 57293.0 1255.5 1279.4 1438.6 1463.6 1474.3 19973.7 30267.9 30382.1 31041.0 31067.5 41240.6 51640.5 51642.5 51689.2 51815.7 443843.0 454728.0 455943.0 456278.0 456360.0 31346.4 31766.0 31971.5 31983.7 32122.0 38003.2 44474.0 44612.6 44971.3 45151.8 28963.4 30169.4 30873.0 30889.4 31185.0 29861.1 30871.7 30873.7 30953.4 31039.0 35753.6 36309.1 36397.6 36749.4 36837.8 30133.2 30568.4 31247.0 31310.7 31410.5 23184.7 24351.5 24361.5 24735.6 24791.6 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:42346.01 Median:21612.12 stddev:123742.41 (572.6%) stddev-sans-first:124049.43 18:43:06 INFO - PROCESS | 1650 | Values: 385.4 404.0 418.6 426.3 448.5 13452.7 20473.5 21672.6 22180.2 22502.2 22030.7 22502.4 23145.9 23146.3 23220.8 5603.7 6711.6 6853.5 6863.2 6908.4 6789.8 6823.8 6877.9 6890.7 6895.3 6506.4 6709.5 6768.6 6772.8 6783.3 6745.4 6775.4 6776.9 6779.5 6899.3 6512.1 6704.7 6715.7 6718.0 6726.0 26621.6 26644.7 27018.0 27155.0 27698.0 2614.5 2934.6 2936.4 2944.8 2978.1 15910.2 16482.8 16509.7 16649.0 17161.2 1816.3 1995.3 2044.7 2053.9 2056.1 131392.3 132555.0 139823.0 139865.3 141967.3 15277.9 21358.9 21507.1 21551.7 21842.9 32904.2 33267.0 33408.0 34372.8 34717.8 7969.2 8439.1 10838.1 10970.0 11019.4 8766.2 8955.7 8977.2 9057.6 9077.0 10305.5 10442.0 10443.2 10547.5 10557.9 4046.4 4110.1 4133.5 4134.9 4135.2 986.4 1079.6 1086.0 1106.3 1153.4 20577.7 22926.4 23249.7 23282.9 23322.4 982.1 996.3 1131.6 1135.6 1158.2 10900.0 11304.8 11312.1 12198.6 12654.1 731694.0 742987.0 743656.3 756588.0 758328.0 119810.0 122919.8 135279.0 140119.0 148047.0 1252.2 1261.7 1287.3 1289.7 1294.3 19064.9 20165.9 20224.5 20340.7 20388.0 63114.4 64196.0 64799.0 64992.4 66201.7 88974.7 89371.6 91038.5 92079.0 92141.6 11381.0 11464.5 11650.2 11888.1 11997.2 12988.5 13024.3 13058.9 13300.0 13307.4 11071.4 11149.5 11169.1 11337.3 11402.9 9989.5 10551.9 10676.9 10727.4 10858.6 11533.8 11569.8 11753.8 11769.9 11845.6 11273.6 11453.9 11790.1 11799.0 11940.8 11256.4 11395.2 11630.9 11653.0 11688.1 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:6628.85 Median:2966.40 stddev:4077.39 (137.5%) stddev-sans-first:4070.94 18:43:06 INFO - PROCESS | 1650 | Values: 1544.7 1560.5 1570.7 1594.2 1595.9 10480.6 10995.1 11005.1 11018.8 11033.5 10635.6 11057.3 11168.6 11171.0 11196.9 2392.7 2420.9 2430.1 2431.8 2433.5 2325.0 2387.4 2419.6 2421.4 2432.3 2341.0 2388.0 2402.9 2429.7 2435.5 2340.8 2391.8 2394.0 2418.1 2419.9 2095.8 2350.0 2383.3 2385.2 2389.5 10925.0 11151.2 11158.6 11163.8 11169.3 5664.5 5816.1 5842.6 5882.5 5900.5 3497.3 3534.9 3618.6 3626.1 3653.7 1115.5 1121.0 1129.7 1234.8 1261.7 13523.9 13861.4 14135.9 15218.6 15417.2 4636.4 4802.2 4810.3 4827.2 4853.4 10146.7 11738.9 11773.2 11788.1 11789.0 4928.0 5579.1 5740.1 5811.4 5851.6 5130.9 5234.8 5240.6 5326.2 5335.5 3915.9 3932.9 3978.1 4000.7 4006.3 919.9 1044.8 1047.5 1060.6 1065.1 899.5 918.6 924.6 927.4 961.8 6350.8 6358.5 6391.5 6435.8 6503.5 944.1 953.5 962.1 967.6 972.5 4068.8 4186.9 4208.5 4300.9 4347.4 13028.5 13633.4 13649.7 13656.9 13716.0 12712.2 13062.5 13257.3 13506.0 13548.3 9894.1 9990.8 10054.0 10127.6 10153.7 6350.6 6407.7 6485.3 6523.2 6577.0 10869.3 11619.9 12042.9 12150.0 12217.3 10118.7 11043.7 11441.9 11756.2 11760.4 9046.3 9106.4 9233.6 9299.0 9389.5 8322.6 10090.6 10305.1 10333.3 10345.1 8636.4 8759.5 8846.2 8848.0 8880.3 8654.0 8692.8 8749.4 8762.3 8782.3 7805.5 7953.8 7968.4 7983.2 7985.2 5735.3 5768.3 5782.5 5938.9 5957.0 5452.4 5737.9 5928.9 5953.2 5992.1 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4318.96 Median:4697.22 stddev:1218.97 (26.0%) stddev-sans-first:1185.67 18:43:06 INFO - PROCESS | 1650 | Values: 363.0 370.4 372.3 376.0 380.1 4439.7 4840.4 4896.5 4948.8 4959.9 4199.9 4762.4 4868.1 4868.6 4874.8 3274.4 3405.8 3510.4 4075.6 4091.8 3412.8 4068.4 4078.4 4101.5 4122.3 3582.9 3676.3 4089.0 4105.9 4110.5 3399.9 3775.3 3779.6 4077.9 4132.9 3690.5 3732.7 3747.8 3799.4 3805.8 3847.8 4381.5 4448.9 4822.5 4885.1 4104.5 4241.3 4748.7 4758.9 4801.7 4827.0 4916.8 5179.5 5203.5 5337.8 3981.2 4122.3 4203.5 4203.7 4231.8 4430.1 5195.4 5323.7 5453.5 5477.8 3974.6 4146.9 4260.0 4300.5 4304.3 3845.7 3892.5 4194.5 4320.0 4370.9 4240.6 4273.0 4357.3 4433.9 4512.5 4116.6 4667.4 5133.5 5143.4 5210.9 1261.0 1349.0 1379.1 1471.0 1485.8 950.6 995.8 996.0 1129.8 1133.5 4405.7 4761.3 4898.0 4926.3 4978.5 4740.9 5006.8 5403.5 5407.4 5497.8 4505.4 4540.3 4657.0 4892.3 4935.5 2434.7 2480.8 2752.2 2800.5 2805.4 5176.1 5501.6 5575.9 5600.3 5605.9 4602.9 5172.8 5394.1 5440.7 5484.3 4859.9 5242.6 5302.4 5632.4 5658.2 4571.0 5366.9 5396.0 5429.5 5445.3 4425.8 4897.7 5019.6 5085.7 5314.5 5300.8 5319.1 5514.4 5526.6 5554.4 3768.7 3887.1 4331.6 4353.5 4363.6 5397.5 5437.3 5464.8 5469.5 5504.4 4441.8 4734.7 4935.6 5183.6 5184.2 4626.4 4716.0 5216.0 5241.8 5314.6 4229.4 4422.7 4746.1 4814.6 5118.8 4426.5 4566.5 5009.3 5227.5 5274.0 4678.5 4826.0 4918.9 5084.7 5101.2 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19899.68 Median:3837.16 stddev:28188.33 (734.6%) stddev-sans-first:28230.67 18:43:06 INFO - PROCESS | 1650 | Values: 734.4 738.5 774.8 824.1 826.2 17519.8 18259.4 18267.0 18273.5 18316.7 16927.6 17024.2 17055.0 17092.4 17097.8 928.7 939.4 947.8 952.4 954.5 931.6 951.2 951.7 952.0 952.3 1001.5 1002.6 1002.9 1002.9 1003.1 876.4 996.1 998.0 998.3 999.4 737.6 738.3 738.7 739.0 739.2 14035.8 15330.3 15331.7 15641.9 15668.1 14066.0 14185.0 14250.2 14260.6 14302.9 11668.8 11719.2 11799.8 11871.1 11878.9 3657.6 3674.3 3679.1 3831.4 3842.9 111998.0 114176.0 116526.0 117311.0 120202.4 10698.4 10768.7 10927.7 10976.9 11038.8 20143.3 22655.9 22736.9 22740.5 22764.7 7442.6 7691.5 7777.6 8033.7 8044.6 4369.9 4378.7 4407.1 4413.8 4440.4 3486.7 3594.8 3598.0 3598.3 3598.9 587.0 607.2 614.6 618.0 621.3 632.5 653.0 676.3 685.5 686.3 9747.3 9988.2 10083.8 10254.2 10406.7 566.3 617.8 683.5 690.4 694.6 4610.9 4809.8 4881.4 4883.7 4890.3 90251.0 92431.0 92732.1 93231.5 93289.0 53010.7 63647.7 76716.6 90107.0 95188.5 68626.3 68645.3 70344.0 70591.9 71659.0 8599.4 9940.8 10147.2 10272.1 10903.3 44111.9 46102.0 47574.8 48574.0 50888.0 54955.7 66291.0 67944.0 68088.0 68442.6 13266.7 13305.3 13563.7 13754.9 13897.4 14263.2 14359.0 14646.2 14687.5 14959.6 13057.4 13062.0 13141.3 13517.4 13595.1 13106.5 13442.7 13803.6 13904.9 14206.4 12457.6 13024.5 13131.7 13299.2 13407.7 8269.6 9873.6 9923.3 10149.8 10293.9 9176.3 10758.1 11019.2 11041.4 11074.6 18:43:06 INFO - PROCESS | 1650 | -------- Summary: end -------- 18:43:06 INFO - PROCESS | 1650 | 18:43:06 INFO - TEST-INFO | 1650: exit 0 18:43:06 INFO - TEST-OK | dromaeo_css | took 1121093ms 18:43:06 INFO - TEST-START | kraken 18:43:06 INFO - operating with platform_type : mac_ 18:43:06 INFO - Initialising browser for kraken test... 18:43:06 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpJVVtSV/profile http://localhost:49239/getInfo.html) 18:43:09 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 18:43:09 INFO - PROCESS | 1657 | colorDepth:24 18:43:09 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 18:43:09 INFO - PROCESS | 1657 | __metrics 18:43:09 ERROR - PROCESS | 1657 | 1491356589276 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4954:11 < observe@XPIProvider.jsm:2917:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:43:09 INFO - PROCESS | 1657 | 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" 18:43:09 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:43:10 INFO - TEST-INFO | 1657: exit 0 18:43:10 INFO - Browser initialized. 18:43:10 INFO - Running cycle 1/1 for kraken test... 18:43:10 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpJVVtSV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:22 INFO - PROCESS | 1658 | RSS: Main: 215625728 18:43:22 INFO - PROCESS | 1658 | 18:43:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:25 INFO - PROCESS | 1658 | RSS: Main: 268468224 18:43:25 INFO - PROCESS | 1658 | 18:43:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 18:43:29 INFO - PROCESS | 1658 | RSS: Main: 279621632 18:43:29 INFO - PROCESS | 1658 | 18:43:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 18:43:32 INFO - PROCESS | 1658 | RSS: Main: 376336384 18:43:32 INFO - PROCESS | 1658 | 18:43:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 18:43:35 INFO - PROCESS | 1658 | RSS: Main: 278720512 18:43:35 INFO - PROCESS | 1658 | 18:43:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:43:38 INFO - PROCESS | 1658 | RSS: Main: 280342528 18:43:38 INFO - PROCESS | 1658 | 18:43:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 18:43:41 INFO - PROCESS | 1658 | RSS: Main: 319160320 18:43:41 INFO - PROCESS | 1658 | 18:43:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 18:43:45 INFO - PROCESS | 1658 | RSS: Main: 322170880 18:43:45 INFO - PROCESS | 1658 | 18:43:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 18:43:49 INFO - PROCESS | 1658 | RSS: Main: 317980672 18:43:49 INFO - PROCESS | 1658 | 18:43:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:43:51 INFO - PROCESS | 1658 | RSS: Main: 293572608 18:43:51 INFO - PROCESS | 1658 | 18:43:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:43:53 INFO - PROCESS | 1658 | RSS: Main: 301064192 18:43:53 INFO - PROCESS | 1658 | 18:43:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:43:56 INFO - PROCESS | 1658 | RSS: Main: 308113408 18:43:56 INFO - PROCESS | 1658 | 18:43:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:00 INFO - PROCESS | 1658 | RSS: Main: 323153920 18:44:00 INFO - PROCESS | 1658 | 18:44:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:04 INFO - PROCESS | 1658 | RSS: Main: 316502016 18:44:04 INFO - PROCESS | 1658 | 18:44:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 18:44:06 INFO - PROCESS | 1658 | RSS: Main: 361091072 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | __start_tp_report 18:44:06 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 18:44:06 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 18:44:06 INFO - PROCESS | 1658 | |i|pagename|runs| 18:44:06 INFO - PROCESS | 1658 | |0;ai-astar;116;118;115;114;117;116;115;117;116;116 18:44:06 INFO - PROCESS | 1658 | |1;audio-beat-detection;149;109;110;143;110;110;109;108;127;109 18:44:06 INFO - PROCESS | 1658 | |2;audio-dft;186;185;184;187;185;186;187;186;186;187 18:44:06 INFO - PROCESS | 1658 | |3;audio-fft;84;59;60;89;60;62;63;60;77;62 18:44:06 INFO - PROCESS | 1658 | |4;audio-oscillator;91;69;86;69;73;94;111;96;68;84 18:44:06 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;90;89;90;91;87;127;90;89;90;89 18:44:06 INFO - PROCESS | 1658 | |6;imaging-darkroom;97;97;96;96;96;97;97;95;97;97 18:44:06 INFO - PROCESS | 1658 | |7;imaging-desaturate;85;84;84;83;84;87;84;83;85;99 18:44:06 INFO - PROCESS | 1658 | |8;json-parse-financial;63;64;62;61;64;65;66;65;64;66 18:44:06 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;62;58;59;70;62;59;59;59;58;60 18:44:06 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;83;84;84;84;86;85;81;87;97;99 18:44:06 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;138;142;139;138;137;145;148;144;181;147 18:44:06 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;169;205;198;165;163;161;203;208;163;164 18:44:06 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;69;65;67;78;81;62;56;78;78;56 18:44:06 INFO - PROCESS | 1658 | __end_tp_report 18:44:06 INFO - PROCESS | 1658 | __start_cc_report 18:44:06 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2238 18:44:06 INFO - PROCESS | 1658 | __end_cc_report 18:44:06 INFO - PROCESS | 1658 | __startTimestamp1491356646242__endTimestamp 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | ------- Summary: start ------- 18:44:06 INFO - PROCESS | 1658 | Number of tests: 14 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.00 stddev:1.15 (1.0%) stddev-sans-first:1.22 18:44:06 INFO - PROCESS | 1658 | Values: 116.0 118.0 115.0 114.0 117.0 116.0 115.0 117.0 116.0 116.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:118.40 Median:110.00 stddev:15.64 (14.2%) stddev-sans-first:12.04 18:44:06 INFO - PROCESS | 1658 | Values: 149.0 109.0 110.0 143.0 110.0 110.0 109.0 108.0 127.0 109.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:185.90 Median:186.00 stddev:0.99 (0.5%) stddev-sans-first:1.05 18:44:06 INFO - PROCESS | 1658 | Values: 186.0 185.0 184.0 187.0 185.0 186.0 187.0 186.0 186.0 187.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:67.60 Median:62.50 stddev:11.29 (18.1%) stddev-sans-first:10.29 18:44:06 INFO - PROCESS | 1658 | Values: 84.0 59.0 60.0 89.0 60.0 62.0 63.0 60.0 77.0 62.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:85.00 stddev:14.35 (16.9%) stddev-sans-first:15.00 18:44:06 INFO - PROCESS | 1658 | Values: 91.0 69.0 86.0 69.0 73.0 94.0 111.0 96.0 68.0 84.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:90.00 stddev:11.92 (13.2%) stddev-sans-first:12.59 18:44:06 INFO - PROCESS | 1658 | Values: 90.0 89.0 90.0 91.0 87.0 127.0 90.0 89.0 90.0 89.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:97.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 18:44:06 INFO - PROCESS | 1658 | Values: 97.0 97.0 96.0 96.0 96.0 97.0 97.0 95.0 97.0 97.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:84.50 stddev:4.78 (5.7%) stddev-sans-first:5.06 18:44:06 INFO - PROCESS | 1658 | Values: 85.0 84.0 84.0 83.0 84.0 87.0 84.0 83.0 85.0 99.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.50 stddev:1.63 (2.5%) stddev-sans-first:1.69 18:44:06 INFO - PROCESS | 1658 | Values: 63.0 64.0 62.0 61.0 64.0 65.0 66.0 65.0 64.0 66.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:59.50 stddev:3.60 (6.0%) stddev-sans-first:3.78 18:44:06 INFO - PROCESS | 1658 | Values: 62.0 58.0 59.0 70.0 62.0 59.0 59.0 59.0 58.0 60.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:85.50 stddev:6.04 (7.1%) stddev-sans-first:6.23 18:44:06 INFO - PROCESS | 1658 | Values: 83.0 84.0 84.0 84.0 86.0 85.0 81.0 87.0 97.0 99.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:144.50 stddev:12.95 (9.0%) stddev-sans-first:13.41 18:44:06 INFO - PROCESS | 1658 | Values: 138.0 142.0 139.0 138.0 137.0 145.0 148.0 144.0 181.0 147.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:183.50 stddev:20.56 (11.2%) stddev-sans-first:21.42 18:44:06 INFO - PROCESS | 1658 | Values: 169.0 205.0 198.0 165.0 163.0 161.0 203.0 208.0 163.0 164.0 18:44:06 INFO - PROCESS | 1658 | 18:44:06 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:73.50 stddev:9.39 (12.8%) stddev-sans-first:9.96 18:44:06 INFO - PROCESS | 1658 | Values: 69.0 65.0 67.0 78.0 81.0 62.0 56.0 78.0 78.0 56.0 18:44:06 INFO - PROCESS | 1658 | -------- Summary: end -------- 18:44:06 INFO - PROCESS | 1658 | 18:44:07 INFO - TEST-INFO | 1658: exit 0 18:44:07 INFO - TEST-OK | kraken | took 60498ms 18:44:07 INFO - SUITE-END | took 1181s 18:44:07 INFO - Completed test suite (00:19:41) 18:44:07 INFO - javascript benchmark 18:44:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7619.028104139862, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2932.6187595612573, 3318.0543545089654, 3355.479900615404, 3368.174797174692, 3380.729541636671, 5994.940050599493, 6007.3692262312425, 6011.188489553909, 6034.456898879105, 6059.394060593939, 5966.5226781857455, 6039.758409663619, 6056.788012419566, 6058.606190597619, 6061.393860613936, 5347.144456886899, 5394.083005889008, 5402.648827826199, 5450.863728406782, 5486.231927530129, 5279.788808447676, 5322.04203243416, 5372.892542149155, 5496.835094947158, 5530.949119667251, 5389.83830485086, 5411.945880541203, 5417.51242388185, 5417.6207665463435, 5418.431064738185, 5380.488853558905, 5394.541463975567, 5399.919001214966, 5402.297701298845, 5402.945970540283, 5247.86880327991, 5353.9732301338245, 5354.196870469461, 5361.169018802092, 5381.300430943991, 6003.159557661931, 6004.489618382399, 6013.278406591194, 6017.969910150465, 6027.457528822383, 6150.831342044997, 6206.038064100072, 6217.906731399033, 6220.440160385542, 6229.781957631507, 5254.8686282842855, 6207.844803879939, 6254.718537665816, 6293.685315734195, 6337.429631333202, 6000.568065736175, 6068.57519973602, 6071.908921366183, 6077.1491991121275, 6137.693115344259, 6202.976508876075, 6469.191351081115, 6486.254080780724, 6509.2839787623325, 6602.8349291268105, 6233.0, 6252.499800015988, 6265.592736472115, 6269.811905642747, 6307.211598550181, 6319.968400158062, 6358.809235722936, 6368.554201205871, 6375.171227740457, 6400.583962042452, 6292.182016337938, 6299.527535434769, 6299.748010079637, 6310.305866354697, 6327.905081423783, 6170.0, 6258.311585725566, 6310.4636105930595, 6313.526485513605, 6327.525435592349, 5155.407128180233, 5163.8192663256605, 5190.065788158167, 5193.350831146106, 5230.293910322134, 4533.637309015271, 4546.090781843644, 4555.589996900295, 4558.5897269245925, 4582.656300777455, 5743.396943320975, 5999.190109335271, 6045.486133678686, 6046.576739628272, 6079.088136779518, 6220.813375598739, 6253.874922501524, 6287.685615719196, 6291.465225455888, 6302.810915672446, 5264.684118952799, 6011.158437818709, 6028.57799953999, 6028.849278768066, 6045.576809623372, 5643.294588176478, 5650.830475085754, 5654.067078932012, 5675.233843431167, 5678.14827775837, 6156.969215153896, 6312.6528040956355, 6330.39861213184, 6446.4520515756685, 6577.506686998398, 6322.557420980487, 6399.55203135776, 6407.878621241357, 6408.00675895237, 6414.93585064162, 6597.340265973365, 6608.107905432705, 6608.669566521558, 6609.933900660932, 6621.6689165540565, 6173.969130154321, 6233.906491402723, 6301.779437719658, 6337.90493142594, 6381.617102973836, 6245.437910588069, 6254.718537665725, 6262.279837818527, 6352.078948552342, 6356.682165891594, 4914.508549145057, 5217.34783152106, 5376.892462150813, 6166.630002199882, 6240.843978900564, 6224.2842073160355, 6228.0, 6280.089387038763, 6296.5592408530965, 6306.653134077486, 6030.547708921761, 6047.455728984412, 6109.8298075392895, 6126.448619624255, 6139.308154112039, 6236.501079913687, 6275.152854364785, 6279.089532017923, 6287.46556542699, 6303.149074874833, 6036.124761909587, 6075.149479072845, 6122.215193971364, 6140.631562106288, 6187.288461826767], "value": 5839.08220403649, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.398537111795, 809.4414853750911, 876.2945828607972, 885.4952676974127, 887.4453466583385, 34730.13174670631, 40816.387754183714, 41565.792171039175, 41601.58398416015, 41632.16735665285, 40676.59323406765, 40893.59106408935, 40971.0, 40972.59027409725, 41050.76847694567, 7420.814479637998, 7564.810879727996, 9208.95395523023, 9359.812803743922, 9394.530273486333, 11018.724531886726, 11187.105031597453, 11329.716757081098, 11369.602063927765, 11371.715707107347, 9762.414255144678, 11159.665210043711, 11189.992900638941, 11193.048590869786, 11232.719182020433, 7216.639168041629, 7381.077365329334, 9700.660476883275, 11119.277246978962, 11165.497552610152, 10723.410212438313, 10954.068904143158, 11071.778564428667, 11075.833862491989, 11085.72285692856, 38258.617413825785, 38510.61489385098, 38530.0, 38543.03642408962, 38573.42139867904, 3739.4951681522916, 3864.381698928158, 3909.902252443683, 3911.2764138634443, 3946.112124771932, 23635.29094127179, 24157.637635435305, 24819.87590062038, 24899.5020099597, 26605.733942660714, 3032.8938487152845, 3071.4932036214, 3133.6239651241767, 3149.0, 3232.7252183564424, 365047.1747641271, 367422.0, 367458.9999999973, 367987.0, 368298.0, 44774.32838507425, 44862.0, 45025.099498009855, 45065.09869802585, 45086.549134508234, 47194.5280547197, 47368.0, 47378.76310618425, 47399.76300118477, 47420.5257947423, 12494.0, 12568.874311256954, 12618.684532886567, 13723.862761372458, 13725.382357793924, 11220.046296064926, 11339.036181924628, 11679.416029198506, 11709.824352634718, 11713.121515886343, 10548.0, 10978.341299522055, 11829.704257393461, 12077.939610302068, 12177.634670959886, 5558.722063896789, 5665.66006039639, 5680.971595141998, 5689.800856969986, 5706.229658996065, 1047.7694907120422, 1050.4065203160342, 1072.3137192197, 1081.4106312059946, 1111.127764704703, 27707.03025394142, 27962.396805321856, 28486.002989895253, 28504.287392815346, 28557.57163642547, 1032.230987914009, 1061.1722856172198, 1107.4960892793758, 1123.4888125902694, 1143.7998350288667, 14101.78847317291, 14115.576532704035, 14115.576532704035, 14414.783778243334, 14425.567232982818, 504139.0, 506576.0, 507328.0, 508786.0, 508966.0, 56576.0, 56822.0, 57115.71442142763, 57274.41804655617, 57293.0, 1255.5166260989586, 1279.4434421026883, 1438.604383794444, 1463.6340914771308, 1474.3144437836243, 19973.700394493804, 30267.94062207812, 30382.088537343916, 31041.0, 31067.533986990664, 41240.58759412367, 51640.48359516507, 51642.48357516527, 51689.224661629356, 51815.74092129515, 443843.0, 454728.0, 455943.0, 456278.0, 456360.0, 31346.373072538878, 31766.0, 31971.520427194075, 31983.68016319807, 32122.03633890987, 38003.239935201695, 44474.0, 44612.55387446084, 44971.32543011923, 45151.77424112859, 28963.420731585673, 30169.37967443921, 30873.0, 30889.382212356075, 31185.0, 29861.10416687503, 30871.691283086882, 30873.691263087083, 30953.380932380776, 31039.0, 35753.64246357503, 36309.09227269234, 36397.636023639425, 36749.44875826918, 36837.81581092077, 30133.246668833453, 30568.388632227674, 31247.0, 31310.686893130776, 31410.52884206693, 23184.652230216223, 24351.51296974015, 24361.51276974486, 24735.62896556517, 24791.628125578492], "value": 18665.431099903602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.3756913799645, 403.9838406463742, 418.6274215947806, 426.26043813982744, 448.49992258631636, 13452.663683407907, 20473.484848484833, 21672.566548669038, 22180.223692170737, 22502.212422565215, 22030.669539956914, 22502.437439064033, 23145.884270578623, 23146.30561083166, 23220.767792322073, 5603.739158689283, 6711.563748356353, 6853.520253582251, 6863.1764188297475, 6908.412784913288, 6789.830254243634, 6823.7611683591085, 6877.931220687779, 6890.655467226644, 6895.275996020421, 6506.414422701956, 6709.463242940553, 6768.593884366929, 6772.796816095525, 6783.287754785751, 6745.426638735713, 6775.424088952445, 6776.898346524806, 6779.49153813466, 6899.275576064541, 6512.12086368339, 6704.731810727614, 6715.731370745164, 6718.0, 6726.0, 26621.600675989877, 26644.73355266442, 27018.0, 27155.049573265038, 27698.0, 2614.542455070355, 2934.5598160275918, 2936.4420760055523, 2944.823310601371, 2978.1214541710247, 15910.204489775464, 16482.75275870863, 16509.66980660392, 16649.0, 17161.227744751515, 1816.2825683854803, 1995.311617491963, 2044.7137400763904, 2053.887036213008, 2056.074766355131, 131392.34303828515, 132555.0, 139823.0, 139865.300673497, 141967.29016354957, 15277.923610381877, 21358.893205534183, 21507.14742053996, 21551.67672484914, 21842.924922132373, 32904.177395565304, 33267.00198994034, 33407.997760067236, 34372.82813585916, 34717.82641086778, 7969.203079692101, 8439.143388454653, 10838.132949364031, 10970.0, 11019.449027548591, 8766.21104100634, 8955.686550970684, 8977.23693486061, 9057.592408341641, 9076.954615227014, 10305.484725763681, 10442.0, 10443.216758743125, 10547.519318624836, 10557.94420557938, 4046.4132700758223, 4110.125973078755, 4133.483314585676, 4134.945588874878, 4135.152293779768, 986.358866736627, 1079.63292480557, 1085.9674209773718, 1106.253279036647, 1153.3541216918552, 20577.69133462999, 22926.42683932915, 23249.651255231183, 23282.883585581963, 23322.416939576648, 982.0523195116747, 996.267743208741, 1131.6095946898306, 1135.6195674448986, 1158.206628459508, 10900.0, 11304.830427543593, 11312.095032397388, 12198.560287942448, 12654.114212005072, 731694.0, 742987.0, 743656.281718588, 756588.0, 758328.0, 119810.0, 122919.77080229262, 135279.0, 140119.0, 148047.0, 1252.1610520950985, 1261.6845788552862, 1287.304855378104, 1289.6840274132896, 1294.3204817470903, 19064.937645948117, 20165.890876001373, 20224.494387640425, 20340.694889576964, 20388.0, 63114.383966096924, 64195.99999999813, 64799.0, 64992.35007649863, 66201.66899165473, 88974.66538001805, 89371.55314223387, 91038.54480727554, 92079.0, 92141.61787573325, 11381.0, 11464.541418343173, 11650.242734222249, 11888.108391870472, 11997.160198786001, 12988.48046078184, 13024.283664398548, 13058.869411305765, 13300.0, 13307.401166947317, 11071.391073491035, 11149.498272577915, 11169.050630696482, 11337.319760814378, 11402.885971140182, 9989.450580218157, 10551.894481055399, 10676.89323106759, 10727.356358618508, 10858.619948302088, 11533.769324613628, 11569.826452603385, 11753.764924701629, 11769.941150294193, 11845.644630661094, 11273.605423810128, 11453.885461145283, 11790.05464098414, 11799.0, 11940.820887686865, 11256.437178141225, 11395.202335836411, 11630.94184529072, 11653.00949419275, 11688.123390745557], "value": 11736.126832431702, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.7373946429107, 1560.5240401677497, 1570.7486802111657, 1594.1550977981667, 1595.9202039898003, 10480.58077676893, 10995.065419439357, 11005.06456951159, 11018.834717479223, 11033.503492342843, 10635.57457701691, 11057.336559806417, 11168.609098681547, 11170.994610485055, 11196.944015279912, 2392.6650268962358, 2420.9273721788372, 2430.1130087518104, 2431.8297719159664, 2433.537627850703, 2325.0, 2387.3673476528734, 2419.588669926117, 2421.3946513371657, 2432.3067925641194, 2341.0401735288533, 2388.044782087162, 2402.939926501843, 2429.7327293997646, 2435.5007223519137, 2340.8010319122895, 2391.80865530758, 2394.0, 2418.069043418279, 2419.8548087114827, 2095.758987716417, 2349.988250058756, 2383.2611890314056, 2385.2009576791797, 2389.522095580891, 10925.0, 11151.163662725328, 11158.553657853676, 11163.83254251187, 11169.329840209613, 5664.518515926151, 5816.1275808628625, 5842.6202296851, 5882.529397648178, 5900.527957763368, 3497.300539892032, 3534.9116272093147, 3618.565772107364, 3626.075350785533, 3653.725970552219, 1115.5482029777952, 1121.0471099565304, 1129.6780417581033, 1234.796258617327, 1261.703499677575, 13523.864761352359, 13861.376238069312, 14135.929320353538, 15218.619534511616, 15417.22913854314, 4636.35091087248, 4802.183628783046, 4810.278458231293, 4827.2035114206465, 4853.39332583427, 10146.695599132037, 11738.882611173778, 11773.17587768865, 11788.115891308185, 11788.997935175435, 4928.0390323886495, 5579.107342825128, 5740.13897915316, 5811.4479124483105, 5851.64890106595, 5130.87172820675, 5234.7906083756225, 5240.633155679066, 5326.201069839478, 5335.493128152819, 3915.882523524299, 3932.9016774580864, 3978.1446988896946, 4000.6799456043445, 4006.2988976928923, 919.9218066464292, 1044.770150566874, 1047.4657924458581, 1060.6181774561153, 1065.0627447845845, 899.5142622983516, 918.5820451694462, 924.5700749151542, 927.4296307770672, 961.8412961861354, 6350.809475715736, 6358.491320694333, 6391.456726178327, 6435.839104022437, 6503.544751867418, 944.1078181118914, 953.4851180362526, 962.100436092256, 967.5500892085214, 972.4797233480195, 4068.7762173080173, 4186.937195942063, 4208.494980602347, 4300.8709738707885, 4347.369631403492, 13028.47886084565, 13633.386497607631, 13649.727005459836, 13656.93171534136, 13716.0, 12712.173708708904, 13062.477500900048, 13257.270850103145, 13506.0, 13548.322583870768, 9894.06006429388, 9990.80018399628, 10054.0, 10127.594896204217, 10153.695389138338, 6350.618962862243, 6407.71165297572, 6485.254195767548, 6523.18460192476, 6576.967115164393, 10869.29349592274, 11619.941900290445, 12042.939785301018, 12150.0, 12217.266963982189, 10118.747031324276, 11043.72390690207, 11441.913018263253, 11756.23584467007, 11760.411979400824, 9046.321525885453, 9106.408083474553, 9233.630654773733, 9298.977112517752, 9389.483578403253, 8322.62548185321, 10090.596376144871, 10305.072543471124, 10333.276670633284, 10345.120664743281, 8636.438631488933, 8759.474431534129, 8846.248068914214, 8848.02671706099, 8880.28957683371, 8654.0, 8692.78268043304, 8749.387542871938, 8762.342824288076, 8782.253508451853, 7805.531668099932, 7953.8409231816195, 7968.402369822171, 7983.2415920487465, 7985.2014798522005, 5735.254416925773, 5768.336641286138, 5782.5373970081455, 5938.881222375615, 5957.0, 5452.372977107683, 5737.885242295214, 5928.911066334094, 5953.226080609493, 5992.13114098462], "value": 5083.0012972440645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.03794943399987, 370.3666729891885, 372.33352299384103, 375.966163045326, 380.0802059017177, 4439.734675617449, 4840.419149702032, 4896.534829191226, 4948.8267910623135, 4959.851204463863, 4199.93700094498, 4762.428508578967, 4868.099401610704, 4868.634852386068, 4874.829380971667, 3274.4106060909025, 3405.7615966882327, 3510.438329867222, 4075.551689314173, 4091.7954102294916, 3412.778169418992, 4068.41008053832, 4078.4298045252644, 4101.487314085739, 4122.2579935611575, 3582.874599389022, 3676.3015027144756, 4089.0, 4105.938410923838, 4110.527289361717, 3399.932001359959, 3775.2826962876966, 3779.5842457329672, 4077.8980525486804, 4132.938005929914, 3690.501782259414, 3732.693557254966, 3747.814357795879, 3799.378428884089, 3805.752626079296, 3847.807609619536, 4381.474223093249, 4448.909012764028, 4822.469528351913, 4885.096257192396, 4104.486939132608, 4241.27898257297, 4748.667593268473, 4758.928616070762, 4801.663883528155, 4827.0209214222, 4916.754162291907, 5179.507946745053, 5203.453637368098, 5337.839864804062, 3981.2435637229123, 4122.3404255319, 4203.453551038346, 4203.717866050853, 4231.788410579458, 4430.136123455927, 5195.350581177353, 5323.707196104174, 5453.509184173404, 5477.808276710336, 3974.5627980922077, 4146.875593732193, 4260.020195355024, 4300.537817142187, 4304.278288684552, 3845.65389114981, 3892.5134358205223, 4194.51094861326, 4320.0, 4370.8907277317685, 4240.575942405735, 4272.957270427256, 4357.346398040319, 4433.896272609187, 4512.458504979358, 4116.5917938006605, 4667.439907211156, 5133.537981581676, 5143.434222235551, 5210.869728256824, 1261.035307989389, 1348.9882588058956, 1379.0691283383676, 1471.0217705225905, 1485.8484674377228, 950.6245033211843, 995.8307087795092, 996.0139461932779, 1129.785340785248, 1133.4616057372848, 4405.722340521213, 4761.262004389325, 4898.0, 4926.310316555685, 4978.477259887732, 4740.857774266778, 5006.77334053152, 5403.48666876654, 5407.351117865881, 5497.752601132958, 4505.36924830524, 4540.273556230987, 4656.95343046572, 4892.260334212852, 4935.481774413706, 2434.707835059804, 2480.751924807541, 2752.229375774786, 2800.467911096885, 2805.424887897976, 5176.120059589879, 5501.559875209973, 5575.944240557543, 5600.327960644749, 5605.943940560624, 4602.930956035662, 5172.844814655567, 5394.085099789597, 5440.708371435304, 5484.341878974548, 4859.930815220646, 5242.648601660689, 5302.3637163540625, 5632.436756324417, 5658.207850900795, 4570.977145114386, 5366.865828354323, 5395.974764794676, 5429.484199001088, 5445.264889239902, 4425.756583387944, 4897.681650692693, 5019.598432125348, 5085.703145697823, 5314.502183973526, 5300.840974770763, 5319.122344813217, 5514.448555144453, 5526.640768350045, 5554.416786237387, 3768.719924445671, 3887.1147493465082, 4331.631811296075, 4353.477582690098, 4363.563643635611, 5397.487238712316, 5437.293151890229, 5464.754086066216, 5469.480399362054, 5504.422035686195, 4441.755703436342, 4734.739589322483, 4935.580475659609, 5183.5593974512585, 5184.222366645034, 4626.444826620827, 4715.976420117878, 5215.973920130375, 5241.764120614505, 5314.62797604164, 4229.407882896458, 4422.71252368595, 4746.145693775154, 4814.614830813596, 5118.820841270538, 4426.535213802505, 4566.45202575693, 5009.323741294878, 5227.503387178212, 5273.973630131824, 4678.461976872703, 4826.0, 4918.9178380756175, 5084.669496482717, 5101.183810590288], "value": 3965.007643206998, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.4271468254761, 738.5310327941755, 774.7598244544191, 824.0770337222305, 826.1985873702511, 17519.82480175198, 18259.36092236772, 18267.0, 18273.451796446112, 18316.725249121257, 16927.576810579743, 17024.23390947407, 17055.0, 17092.401765938193, 17097.82902170978, 928.7353104365258, 939.3659279985982, 947.7725345916965, 952.4475804033679, 954.4941181173989, 931.580788645109, 951.1867353412836, 951.6954574536152, 952.0289304908989, 952.2715122930963, 1001.4892404873494, 1002.5989604158322, 1002.8696269484993, 1002.9448380339079, 1003.0521157506159, 876.3514998900831, 996.0637001218831, 997.9700608981742, 998.2812375089924, 999.3903718731568, 737.6496164321958, 738.2543630932743, 738.6934422214774, 738.9556626602421, 739.2238149942539, 14035.78946315806, 15330.310136043905, 15331.693366132615, 15641.921790390976, 15668.138252396113, 14066.0, 14185.0, 14250.2162381069, 14260.572182834532, 14302.928485357506, 11668.824967625493, 11719.179657423983, 11799.764004719944, 11871.109666775026, 11878.940605297004, 3657.5976642569294, 3674.2651469706166, 3679.0986208379118, 3831.3678243090135, 3842.9423558646645, 111998.0, 114176.0, 116526.0, 117310.99999999914, 120202.3989880045, 10698.405435247609, 10768.676939691823, 10927.743309519348, 10976.890231097586, 11038.834417483744, 20143.294984675467, 22655.88672056629, 22736.863156842424, 22740.545189096127, 22764.74793886319, 7442.553446793209, 7691.50005249657, 7777.5722335270975, 8033.718819841278, 8044.597770111588, 4369.890752731144, 4378.715383500063, 4407.118576284756, 4413.775274307349, 4440.35614835847, 3486.651334866493, 3594.8022858742497, 3598.0105470995686, 3598.2803439312243, 3598.8740394086603, 586.9559783016291, 607.1803065861052, 614.6066517428849, 618.0482057631198, 621.3320680268729, 632.4940047961612, 653.0237295243642, 676.2696288008939, 685.4584877946467, 686.3342557719004, 9747.317687761788, 9988.200943924468, 10083.848742268872, 10254.179665626732, 10406.739831504121, 566.2751677852356, 617.8115674719254, 683.4634811672813, 690.4234963805279, 694.6422592364936, 4610.907781844345, 4809.759512024385, 4881.4142302923165, 4883.731394773251, 4890.339804126468, 90251.0, 92431.0, 92732.0726792737, 93231.53384233035, 93289.0, 53010.73494632502, 63647.72704545883, 76716.61641691756, 90107.0, 95188.52405737927, 68626.313736863, 68645.3135468639, 70344.0, 70591.9411208822, 71659.0, 8599.418002072849, 9940.807103147668, 10147.188224942134, 10272.126869216201, 10903.29128606638, 44111.897202570195, 46102.0, 47574.76212618915, 48574.0, 50888.0, 54955.725221373636, 66291.0, 67943.98084028644, 68088.0, 68442.63114737778, 13266.668333291746, 13305.268210248529, 13563.660908477366, 13754.93122534381, 13897.374618142007, 14263.215523146326, 14359.0, 14646.194459304841, 14687.485937992118, 14959.551213463179, 13057.412416441473, 13062.0, 13141.269143108282, 13517.391717372939, 13595.116317439335, 13106.475740970254, 13442.663933401744, 13803.585892423243, 13904.930475347559, 14206.360713767699, 12457.563985260835, 13024.52018711367, 13131.737365252451, 13299.202047876772, 13407.664808379868, 8269.62786674589, 9873.605055797974, 9923.255755818487, 10149.79700405973, 10293.897061029593, 9176.312034247703, 10758.128856506659, 11019.173561983043, 11041.39272340029, 11074.557017719524], "value": 7587.189878226929, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 115.0, 114.0, 117.0, 116.0, 115.0, 117.0, 116.0, 116.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 110.0, 143.0, 110.0, 110.0, 109.0, 108.0, 127.0, 109.0], "value": 118.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 187.0, 185.0, 186.0, 187.0, 186.0, 186.0, 187.0], "value": 185.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 60.0, 89.0, 60.0, 62.0, 63.0, 60.0, 77.0, 62.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 86.0, 69.0, 73.0, 94.0, 111.0, 96.0, 68.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 90.0, 91.0, 87.0, 127.0, 90.0, 89.0, 90.0, 89.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 96.0, 96.0, 97.0, 97.0, 95.0, 97.0, 97.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 83.0, 84.0, 87.0, 84.0, 83.0, 85.0, 99.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 61.0, 64.0, 65.0, 66.0, 65.0, 64.0, 66.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 59.0, 70.0, 62.0, 59.0, 59.0, 59.0, 58.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 84.0, 86.0, 85.0, 81.0, 87.0, 97.0, 99.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 139.0, 138.0, 137.0, 145.0, 148.0, 144.0, 181.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 205.0, 198.0, 165.0, 163.0, 161.0, 203.0, 208.0, 163.0, 164.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 67.0, 78.0, 81.0, 62.0, 56.0, 78.0, 78.0, 56.0], "value": 69.0, "unit": "score"}]}]} 18:44:07 INFO - Return code: 0 18:44:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:07 ERROR - # TBPL SUCCESS # 18:44:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:44:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:44:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:44:07 INFO - 2017-04-04 18:44:07.554 screenresolution[1662:10829] starting screenresolution argv=screenresolution get 18:44:07 INFO - 2017-04-04 18:44:07.557 screenresolution[1662:10829] Display 0: 1600x1200x32@60 18:44:07 INFO - 2017-04-04 18:44:07.563 screenresolution[1663:10832] starting screenresolution argv=screenresolution list 18:44:07 INFO - Available Modes on Display 0 18:44:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:44:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:44:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:44:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:44:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:44:07 INFO - Intel Iris: 18:44:07 INFO - Chipset Model: Intel Iris 18:44:07 INFO - Type: GPU 18:44:07 INFO - Bus: Built-In 18:44:07 INFO - VRAM (Dynamic, Max): 1536 MB 18:44:07 INFO - Vendor: Intel (0x8086) 18:44:07 INFO - Device ID: 0x0a2e 18:44:07 INFO - Revision ID: 0x0009 18:44:07 INFO - Displays: 18:44:07 INFO - Display: 18:44:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:44:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:44:07 INFO - Display Serial Number: 1600x1200 60 18:44:07 INFO - Main Display: Yes 18:44:07 INFO - Mirror: Off 18:44:07 INFO - Online: Yes 18:44:07 INFO - Rotation: Supported 18:44:07 INFO - Return code: 0 18:44:07 INFO - Running post-action listener: _resource_record_post_action 18:44:07 INFO - [mozharness: 2017-04-05 01:44:07.808086Z] Finished run-tests step (success) 18:44:07 INFO - Running post-run listener: _resource_record_post_run 18:44:07 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 31276032; Write bytes: 507352064; Read time: 199; Write time: 1542 18:44:07 INFO - TinderboxPrint: CPU usage
54.0% 18:44:07 INFO - TinderboxPrint: I/O read bytes / time
31,276,032 / 199 18:44:07 INFO - TinderboxPrint: I/O write bytes / time
507,352,064 / 1,542 18:44:07 INFO - TinderboxPrint: CPU idle
2,213.4 (46.0%) 18:44:07 INFO - TinderboxPrint: CPU system
956.8 (19.9%) 18:44:07 INFO - TinderboxPrint: CPU user
1,639.4 (34.1%) 18:44:07 INFO - TinderboxPrint: Swap in / out
235,839,488 / 0 18:44:07 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201697792; Write bytes: 199258112; Read time: 15648; Write time: 427 18:44:07 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 29490176; Write bytes: 296992256; Read time: 185; Write time: 1075 18:44:08 INFO - Running post-run listener: _upload_blobber_files 18:44:08 INFO - Blob upload gear active. 18:44:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:08 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 18:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:44:08 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:44:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:09 INFO - (blobuploader) - INFO - Done attempting. 18:44:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:44:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:44:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:10 INFO - (blobuploader) - INFO - Done attempting. 18:44:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:11 INFO - (blobuploader) - INFO - Done attempting. 18:44:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:14 INFO - (blobuploader) - INFO - Done attempting. 18:44:14 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:14 INFO - Return code: 0 18:44:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e87efffe0246f22d61f5d9b872c9783358030160c3ad72fdeb30beba0232d0b3f2e90112d8a557b6a289b3b0182c6fb91d95daddd94ef8b34e9ef97c6a3adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90f7d0af3a2b93a56046483f34b80444ff633e2839192af1847dd17111513562bfd6bee7afd75c4e3121a5e6b3fd74ebf0ec68f347d5015f4bcc382865b596b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0b8463204e4fc71daa44f28ea8c7827b70e7cdbc9aed3e72785fad839726e3019531235447f3bee1c74267174c74013f65d0bc529b8b06ae80defcbbd475f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/667c44788848ad66b9aa69b045ae161779114f8b0107f459e6635d6be49b1a56649166f0fef93a42120a9e101f962dbada764548495121fb3539e30169d1443b"} 18:44:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:14 INFO - Contents: 18:44:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e87efffe0246f22d61f5d9b872c9783358030160c3ad72fdeb30beba0232d0b3f2e90112d8a557b6a289b3b0182c6fb91d95daddd94ef8b34e9ef97c6a3adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90f7d0af3a2b93a56046483f34b80444ff633e2839192af1847dd17111513562bfd6bee7afd75c4e3121a5e6b3fd74ebf0ec68f347d5015f4bcc382865b596b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0b8463204e4fc71daa44f28ea8c7827b70e7cdbc9aed3e72785fad839726e3019531235447f3bee1c74267174c74013f65d0bc529b8b06ae80defcbbd475f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/667c44788848ad66b9aa69b045ae161779114f8b0107f459e6635d6be49b1a56649166f0fef93a42120a9e101f962dbada764548495121fb3539e30169d1443b"} 18:44:14 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:14 INFO - Copying logs to upload dir... 18:44:14 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.959872 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-04 18:44:14.344919) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 18:44:14.348921) ========= 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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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/Mozilla-Aurora/sha512/54e87efffe0246f22d61f5d9b872c9783358030160c3ad72fdeb30beba0232d0b3f2e90112d8a557b6a289b3b0182c6fb91d95daddd94ef8b34e9ef97c6a3adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90f7d0af3a2b93a56046483f34b80444ff633e2839192af1847dd17111513562bfd6bee7afd75c4e3121a5e6b3fd74ebf0ec68f347d5015f4bcc382865b596b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0b8463204e4fc71daa44f28ea8c7827b70e7cdbc9aed3e72785fad839726e3019531235447f3bee1c74267174c74013f65d0bc529b8b06ae80defcbbd475f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/667c44788848ad66b9aa69b045ae161779114f8b0107f459e6635d6be49b1a56649166f0fef93a42120a9e101f962dbada764548495121fb3539e30169d1443b"} build_url:https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011032 build_url: 'https://queue.taskcluster.net/v1/task/dcF9d13GQkK8ujbzqtH60A/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e87efffe0246f22d61f5d9b872c9783358030160c3ad72fdeb30beba0232d0b3f2e90112d8a557b6a289b3b0182c6fb91d95daddd94ef8b34e9ef97c6a3adc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/90f7d0af3a2b93a56046483f34b80444ff633e2839192af1847dd17111513562bfd6bee7afd75c4e3121a5e6b3fd74ebf0ec68f347d5015f4bcc382865b596b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f0b8463204e4fc71daa44f28ea8c7827b70e7cdbc9aed3e72785fad839726e3019531235447f3bee1c74267174c74013f65d0bc529b8b06ae80defcbbd475f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/667c44788848ad66b9aa69b045ae161779114f8b0107f459e6635d6be49b1a56649166f0fef93a42120a9e101f962dbada764548495121fb3539e30169d1443b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-04 18:44:14.383535) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 18:44:14.383907) ========= 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.kQIQrDBMd2/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.g4xhqUGxMy/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.005130 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 18:44:14.434962) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 18:44:14.435556) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-04 18:44:14.435963) ========= ========= Total master_lag: 0.18 =========