builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0386 starttime: 1486692081.41 results: success (0) buildid: 20170209163711 builduid: 48609584f3bd489685b31d41ba05a535 revision: 00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.412239) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.412688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.413600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.440176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.440568) ========= 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.4GZtLzjwcy/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.lONOzqYzPG/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-09 18:01:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-02-09 18:01:21 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.596536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.597149) ========= 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.4GZtLzjwcy/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.lONOzqYzPG/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.025039 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:21.645755) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 18:01:21.646134) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4GZtLzjwcy/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.lONOzqYzPG/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-09 18:01:21,739 truncating revision to first 12 chars 2017-02-09 18:01:21,739 Setting DEBUG logging. 2017-02-09 18:01:21,739 attempt 1/10 2017-02-09 18:01:21,739 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/00beaa76c5b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 18:01:23,292 unpacking tar archive at: mozilla-central-00beaa76c5b1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.840988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 18:01:23.510524) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:23.510861) ========= script_repo_revision: 00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:23.511264) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:23.511533) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 18:01:23.528569) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-09 18:01:23.528960) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4GZtLzjwcy/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.lONOzqYzPG/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:01:23 INFO - MultiFileLogger online at 20170209 18:01:23 in /builds/slave/test 18:01:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:01:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:01:23 INFO - {'append_to_log': False, 18:01:23 INFO - 'base_work_dir': '/builds/slave/test', 18:01:23 INFO - 'blob_upload_branch': 'Firefox', 18:01:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:01:23 INFO - 'branch': 'Firefox', 18:01:23 INFO - 'buildbot_json_path': 'buildprops.json', 18:01:23 INFO - 'config_files': ('talos/mac_config.py',), 18:01:23 INFO - 'default_actions': ('clobber', 18:01:23 INFO - 'read-buildbot-config', 18:01:23 INFO - 'download-and-extract', 18:01:23 INFO - 'populate-webroot', 18:01:23 INFO - 'create-virtualenv', 18:01:23 INFO - 'install', 18:01:23 INFO - 'run-tests'), 18:01:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:01:23 INFO - 'download_minidump_stackwalk': True, 18:01:23 INFO - 'download_symbols': 'ondemand', 18:01:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:01:23 INFO - 'tooltool.py': '/tools/tooltool.py', 18:01:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:01:23 INFO - '/tools/misc-python/virtualenv.py')}, 18:01:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:01:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:01:23 INFO - 'installer_path': 'installer.exe', 18:01:23 INFO - 'log_level': 'info', 18:01:23 INFO - 'log_name': 'talos', 18:01:23 INFO - 'log_to_console': True, 18:01:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:01:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:01:23 INFO - 'opt_config_files': (), 18:01:23 INFO - 'pip_index': False, 18:01:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:23 INFO - 'cmd': ('bash', 18:01:23 INFO - '-c', 18:01:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:23 INFO - 'enabled': True, 18:01:23 INFO - 'halt_on_failure': False, 18:01:23 INFO - 'name': 'check_screen_resolution'},), 18:01:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:01:23 INFO - 'cmd': ('bash', 18:01:23 INFO - '-c', 18:01:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:01:23 INFO - 'enabled': True, 18:01:23 INFO - 'halt_on_failure': False, 18:01:23 INFO - 'name': 'check_screen_resolution'},), 18:01:23 INFO - 'run_cmd_checks_enabled': True, 18:01:23 INFO - 'sps_profile': False, 18:01:23 INFO - 'sps_profile_interval': 0, 18:01:23 INFO - 'suite': 'dromaeojs-e10s', 18:01:23 INFO - 'system_bits': '32', 18:01:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:01:23 INFO - 'title': 't-yosemite-r7-0386', 18:01:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:01:23 INFO - 'use_talos_json': True, 18:01:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:01:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:01:23 INFO - 'work_dir': 'build'} 18:01:23 INFO - [mozharness: 2017-02-10 02:01:23.729378Z] Running clobber step. 18:01:23 INFO - Running pre-action listener: _resource_record_pre_action 18:01:23 INFO - Running main action method: clobber 18:01:23 INFO - rmtree: /builds/slave/test/build 18:01:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:01:26 INFO - Running post-action listener: _resource_record_post_action 18:01:26 INFO - [mozharness: 2017-02-10 02:01:26.067999Z] Finished clobber step (success) 18:01:26 INFO - [mozharness: 2017-02-10 02:01:26.068129Z] Running read-buildbot-config step. 18:01:26 INFO - Running pre-action listener: _resource_record_pre_action 18:01:26 INFO - Running main action method: read_buildbot_config 18:01:26 INFO - Using buildbot properties: 18:01:26 INFO - { 18:01:26 INFO - "project": "", 18:01:26 INFO - "product": "firefox", 18:01:26 INFO - "script_repo_revision": "production", 18:01:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:01:26 INFO - "repository": "", 18:01:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:01:26 INFO - "buildid": "20170209163711", 18:01:26 INFO - "pgo_build": "False", 18:01:26 INFO - "basedir": "/builds/slave/test", 18:01:26 INFO - "buildnumber": 57, 18:01:26 INFO - "slavename": "t-yosemite-r7-0386", 18:01:26 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:01:26 INFO - "platform": "yosemite_r7", 18:01:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:01:26 INFO - "branch": "mozilla-central", 18:01:26 INFO - "repo_path": "mozilla-central", 18:01:26 INFO - "revision": "00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447", 18:01:26 INFO - "stage_platform": "macosx64", 18:01:26 INFO - "builduid": "48609584f3bd489685b31d41ba05a535", 18:01:26 INFO - "slavebuilddir": "test" 18:01:26 INFO - } 18:01:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:01:26 INFO - Running post-action listener: _resource_record_post_action 18:01:26 INFO - [mozharness: 2017-02-10 02:01:26.071461Z] Finished read-buildbot-config step (success) 18:01:26 INFO - [mozharness: 2017-02-10 02:01:26.071552Z] Running download-and-extract step. 18:01:26 INFO - Running pre-action listener: _resource_record_pre_action 18:01:26 INFO - Running main action method: download_and_extract 18:01:26 INFO - mkdir: /builds/slave/test/build/tests 18:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:26 INFO - trying https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:01:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:01:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 18:01:27 INFO - Downloaded 1199 bytes. 18:01:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:01:27 INFO - Using the following test package requirements: 18:01:27 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:01:27 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:01:27 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:01:27 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'jsshell-mac.zip'], 18:01:27 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:01:27 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:01:27 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:01:27 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:01:27 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:01:27 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:01:27 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:01:27 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:01:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:01:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:27 INFO - Fetch https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:01:28 INFO - Expected file size: 34942007 18:01:28 INFO - Obtained file size: 34942007 18:01:35 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:01:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:01:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:01:35 INFO - Fetch https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:01:36 INFO - Expected file size: 34942007 18:01:36 INFO - Obtained file size: 34942007 18:01:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:01:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:01:43 INFO - Fetch https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:01:44 INFO - Expected file size: 14403454 18:01:44 INFO - Obtained file size: 14403454 18:01:45 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:01:45 INFO - trying https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:01:45 INFO - Downloading https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:01:47 INFO - Downloaded 70359156 bytes. 18:01:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:01:47 INFO - mkdir: /builds/slave/test/properties 18:01:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:47 INFO - Contents: 18:01:47 INFO - build_url:https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:01:48 INFO - Running post-action listener: _resource_record_post_action 18:01:48 INFO - Running post-action listener: set_extra_try_arguments 18:01:48 INFO - [mozharness: 2017-02-10 02:01:48.052220Z] Finished download-and-extract step (success) 18:01:48 INFO - [mozharness: 2017-02-10 02:01:48.052341Z] Running populate-webroot step. 18:01:48 INFO - Running pre-action listener: _resource_record_pre_action 18:01:48 INFO - Running main action method: populate_webroot 18:01:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:48 INFO - '--tppagecycles', 18:01:48 INFO - '7'], 18:01:48 INFO - 'tests': ['tsvgm']}}, 18:01:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['damp', 'tps']}, 18:01:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'tests': ['damp', 'tps']}, 18:01:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['dromaeo_dom']}, 18:01:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['basic_compositor_video']}, 18:01:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:01:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['a11yr', 18:01:48 INFO - 'ts_paint', 18:01:48 INFO - 'tpaint', 18:01:48 INFO - 'sessionrestore', 18:01:48 INFO - 'sessionrestore_no_auto_restore', 18:01:48 INFO - 'tabpaint']}, 18:01:48 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:48 INFO - 'ts_paint', 18:01:48 INFO - 'tpaint', 18:01:48 INFO - 'sessionrestore', 18:01:48 INFO - 'sessionrestore_no_auto_restore', 18:01:48 INFO - 'tabpaint']}, 18:01:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:48 INFO - 'ts_paint', 18:01:48 INFO - 'tpaint', 18:01:48 INFO - 'sessionrestore', 18:01:48 INFO - 'sessionrestore_no_auto_restore', 18:01:48 INFO - 'tabpaint']}, 18:01:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:48 INFO - 'ts_paint', 18:01:48 INFO - 'tpaint', 18:01:48 INFO - 'sessionrestore', 18:01:48 INFO - 'sessionrestore_no_auto_restore', 18:01:48 INFO - 'tabpaint']}, 18:01:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['a11yr', 18:01:48 INFO - 'ts_paint', 18:01:48 INFO - 'tpaint', 18:01:48 INFO - 'sessionrestore', 18:01:48 INFO - 'sessionrestore_no_auto_restore', 18:01:48 INFO - 'tabpaint']}, 18:01:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['a11yr', 18:01:48 INFO - 'ts_paint', 18:01:48 INFO - 'tpaint', 18:01:48 INFO - 'sessionrestore', 18:01:48 INFO - 'sessionrestore_no_auto_restore', 18:01:48 INFO - 'tabpaint']}, 18:01:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['tsvgx', 18:01:48 INFO - 'tsvgr_opacity', 18:01:48 INFO - 'tart', 18:01:48 INFO - 'tscrollx', 18:01:48 INFO - 'cart', 18:01:48 INFO - 'tsvg_static']}, 18:01:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:48 INFO - 'tsvgr_opacity', 18:01:48 INFO - 'tart', 18:01:48 INFO - 'tscrollx', 18:01:48 INFO - 'cart', 18:01:48 INFO - 'tsvg_static']}, 18:01:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'talos_options': ['--disable-e10s'], 18:01:48 INFO - 'tests': ['tp5o']}, 18:01:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:01:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'talos_options': ['--disable-e10s', 18:01:48 INFO - '--xperf_path', 18:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:48 INFO - 'tests': ['tp5n']}, 18:01:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:01:48 INFO - 'talos_options': ['--xperf_path', 18:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:48 INFO - 'tests': ['tp5n']}}, 18:01:48 INFO - 'talos.zip': {'path': '', 18:01:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:48 INFO - Running post-action listener: _resource_record_post_action 18:01:48 INFO - [mozharness: 2017-02-10 02:01:48.070605Z] Finished populate-webroot step (success) 18:01:48 INFO - [mozharness: 2017-02-10 02:01:48.070716Z] Running create-virtualenv step. 18:01:48 INFO - Running pre-action listener: _resource_record_pre_action 18:01:48 INFO - Running main action method: create_virtualenv 18:01:48 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:48 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:01:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:48 INFO - Using real prefix '/tools/python27' 18:01:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:51 INFO - Installing pip.................done. 18:01:51 INFO - Return code: 0 18:01:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:01:52 INFO - Reading from file tmpfile_stdout 18:01:52 INFO - Output received: 18:01:52 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:01:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:01:52 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:01:52 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:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:52 INFO - 'HOME': '/Users/cltbld', 18:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:52 INFO - 'LOGNAME': 'cltbld', 18:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:52 INFO - 'MOZ_NO_REMOTE': '1', 18:01:52 INFO - 'NO_EM_RESTART': '1', 18:01:52 INFO - 'PAGER': '/bin/cat', 18:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:52 INFO - 'PWD': '/builds/slave/test', 18:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:52 INFO - 'SHELL': '/bin/bash', 18:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:01:52 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:52 INFO - 'USER': 'cltbld', 18:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:52 INFO - 'XPC_FLAGS': '0x0', 18:01:52 INFO - 'XPC_SERVICE_NAME': '0', 18:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:52 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:01:52 INFO - Cleaning up... 18:01:52 INFO - Return code: 0 18:01:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:01:52 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:01:52 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:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:52 INFO - 'HOME': '/Users/cltbld', 18:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:52 INFO - 'LOGNAME': 'cltbld', 18:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:52 INFO - 'MOZ_NO_REMOTE': '1', 18:01:52 INFO - 'NO_EM_RESTART': '1', 18:01:52 INFO - 'PAGER': '/bin/cat', 18:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:52 INFO - 'PWD': '/builds/slave/test', 18:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:52 INFO - 'SHELL': '/bin/bash', 18:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:01:52 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:52 INFO - 'USER': 'cltbld', 18:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:52 INFO - 'XPC_FLAGS': '0x0', 18:01:52 INFO - 'XPC_SERVICE_NAME': '0', 18:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:52 INFO - Downloading/unpacking psutil>=3.1.1 18:01: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:01: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:01: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:01: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:01: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:01: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:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:55 INFO - Installing collected packages: psutil 18:01:55 INFO - Running setup.py install for psutil 18:01:56 INFO - building 'psutil._psutil_osx' extension 18:01:56 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:01:56 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:01:56 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:01:56 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:01:56 INFO - building 'psutil._psutil_posix' extension 18:01:56 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:01:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:01:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:01:56 INFO - ^ 18:01:56 INFO - 1 warning generated. 18:01:56 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:01:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:57 INFO - Successfully installed psutil 18:01:57 INFO - Cleaning up... 18:01:57 INFO - Return code: 0 18:01:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:57 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:01:57 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:01:57 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:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:57 INFO - 'HOME': '/Users/cltbld', 18:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:57 INFO - 'LOGNAME': 'cltbld', 18:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:57 INFO - 'MOZ_NO_REMOTE': '1', 18:01:57 INFO - 'NO_EM_RESTART': '1', 18:01:57 INFO - 'PAGER': '/bin/cat', 18:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:57 INFO - 'PWD': '/builds/slave/test', 18:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:57 INFO - 'SHELL': '/bin/bash', 18:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:01:57 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:57 INFO - 'USER': 'cltbld', 18:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:57 INFO - 'XPC_FLAGS': '0x0', 18:01:57 INFO - 'XPC_SERVICE_NAME': '0', 18:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:01:57 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:01:57 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:01:57 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:01:57 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:01:57 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:01:57 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:01:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:02:00 INFO - Installing collected packages: mozsystemmonitor 18:02:00 INFO - Running setup.py install for mozsystemmonitor 18:02:00 INFO - Successfully installed mozsystemmonitor 18:02:00 INFO - Cleaning up... 18:02:00 INFO - Return code: 0 18:02:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:02:00 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:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:00 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:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:00 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02:00 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:02:00 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:02:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:00 INFO - 'HOME': '/Users/cltbld', 18:02:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:00 INFO - 'LOGNAME': 'cltbld', 18:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:00 INFO - 'MOZ_NO_REMOTE': '1', 18:02:00 INFO - 'NO_EM_RESTART': '1', 18:02:00 INFO - 'PAGER': '/bin/cat', 18:02:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:00 INFO - 'PWD': '/builds/slave/test', 18:02:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:00 INFO - 'SHELL': '/bin/bash', 18:02:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:00 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:00 INFO - 'USER': 'cltbld', 18:02:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:00 INFO - 'XPC_FLAGS': '0x0', 18:02:00 INFO - 'XPC_SERVICE_NAME': '0', 18:02:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:00 INFO - Downloading/unpacking jsonschema==2.5.1 18:02: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:02: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:02: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:02: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:02: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:02: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:02:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:03 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:03 INFO - warning: no files found matching '*.txt' 18:02:03 INFO - no previously-included directories found matching 'build' 18:02:03 INFO - no previously-included directories found matching 'dist' 18:02:03 INFO - no previously-included directories found matching '.git*' 18:02:03 INFO - Installing collected packages: jsonschema, functools32 18:02:03 INFO - Running setup.py install for functools32 18:02:03 INFO - warning: no files found matching '*.txt' 18:02:03 INFO - no previously-included directories found matching 'build' 18:02:03 INFO - no previously-included directories found matching 'dist' 18:02:03 INFO - no previously-included directories found matching '.git*' 18:02:03 INFO - Successfully installed jsonschema functools32 18:02:03 INFO - Cleaning up... 18:02:04 INFO - Return code: 0 18:02:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:02: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:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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', '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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:02: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 functools32==3.2.3-2 18:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:04 INFO - 'HOME': '/Users/cltbld', 18:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:04 INFO - 'LOGNAME': 'cltbld', 18:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:04 INFO - 'MOZ_NO_REMOTE': '1', 18:02:04 INFO - 'NO_EM_RESTART': '1', 18:02:04 INFO - 'PAGER': '/bin/cat', 18:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:04 INFO - 'PWD': '/builds/slave/test', 18:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:04 INFO - 'SHELL': '/bin/bash', 18:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:04 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:04 INFO - 'USER': 'cltbld', 18:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:04 INFO - 'XPC_FLAGS': '0x0', 18:02:04 INFO - 'XPC_SERVICE_NAME': '0', 18:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:02:04 INFO - Cleaning up... 18:02:04 INFO - Return code: 0 18:02:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02: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:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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', '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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:02: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 blobuploader==1.2.4 18:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:04 INFO - 'HOME': '/Users/cltbld', 18:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:04 INFO - 'LOGNAME': 'cltbld', 18:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:04 INFO - 'MOZ_NO_REMOTE': '1', 18:02:04 INFO - 'NO_EM_RESTART': '1', 18:02:04 INFO - 'PAGER': '/bin/cat', 18:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:04 INFO - 'PWD': '/builds/slave/test', 18:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:04 INFO - 'SHELL': '/bin/bash', 18:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:04 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:04 INFO - 'USER': 'cltbld', 18:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:04 INFO - 'XPC_FLAGS': '0x0', 18:02:04 INFO - 'XPC_SERVICE_NAME': '0', 18:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:02:04 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:02:04 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:02:04 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:02:04 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:02:04 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:02:04 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:02:07 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 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:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 INFO - Downloading docopt-0.6.1.tar.gz 18:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:08 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:08 INFO - Running setup.py install for blobuploader 18:02:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:08 INFO - Running setup.py install for requests 18:02:09 INFO - Running setup.py install for docopt 18:02:09 INFO - Successfully installed blobuploader requests docopt 18:02:09 INFO - Cleaning up... 18:02:09 INFO - Return code: 0 18:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:09 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02:09 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:02:09 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:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:09 INFO - 'HOME': '/Users/cltbld', 18:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:09 INFO - 'LOGNAME': 'cltbld', 18:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:09 INFO - 'MOZ_NO_REMOTE': '1', 18:02:09 INFO - 'NO_EM_RESTART': '1', 18:02:09 INFO - 'PAGER': '/bin/cat', 18:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:09 INFO - 'PWD': '/builds/slave/test', 18:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:09 INFO - 'SHELL': '/bin/bash', 18:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:09 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:09 INFO - 'USER': 'cltbld', 18:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:09 INFO - 'XPC_FLAGS': '0x0', 18:02:09 INFO - 'XPC_SERVICE_NAME': '0', 18:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:09 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-1_XIpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:09 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-PptNl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:09 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3HQdDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:09 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-cCtYOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-O4vro2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pjbCqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-agC5Uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-M6YTTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-jDK9zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-UiNFhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-RPnRXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-mkCjZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pxdUIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:10 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-S9kvtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:11 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7Gr9qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:11 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-v7hBtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:11 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Ms3Lce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:11 INFO - Running setup.py install for manifestparser 18:02:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:11 INFO - Running setup.py install for mozcrash 18:02:11 INFO - Running setup.py install for mozdebug 18:02:11 INFO - Running setup.py install for mozdevice 18:02:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:02:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozfile 18:02:12 INFO - Running setup.py install for mozhttpd 18:02:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozinfo 18:02:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozInstall 18:02:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for mozleak 18:02:12 INFO - Running setup.py install for mozlog 18:02:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:12 INFO - Running setup.py install for moznetwork 18:02:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozprocess 18:02:13 INFO - Running setup.py install for mozprofile 18:02:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozrunner 18:02:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Running setup.py install for mozscreenshot 18:02:13 INFO - Running setup.py install for moztest 18:02:13 INFO - Running setup.py install for mozversion 18:02:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:13 INFO - Cleaning up... 18:02:13 INFO - Return code: 0 18:02:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:13 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02:13 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:02:13 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:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:13 INFO - 'HOME': '/Users/cltbld', 18:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:13 INFO - 'LOGNAME': 'cltbld', 18:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:13 INFO - 'MOZ_NO_REMOTE': '1', 18:02:13 INFO - 'NO_EM_RESTART': '1', 18:02:13 INFO - 'PAGER': '/bin/cat', 18:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:13 INFO - 'PWD': '/builds/slave/test', 18:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:13 INFO - 'SHELL': '/bin/bash', 18:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:13 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:13 INFO - 'USER': 'cltbld', 18:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:13 INFO - 'XPC_FLAGS': '0x0', 18:02:13 INFO - 'XPC_SERVICE_NAME': '0', 18:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-CFIJXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-4BXblX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-mL2_OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-2kXueD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-uaWaC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-PWMuuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-TJGtnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:14 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:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:14 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-RmJeTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-oUhoEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-AckqL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-T19pFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-YzDxMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-9Xh6Hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-b7v1k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ghUzoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-C0qar4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:15 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:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:15 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-tl4wuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:18 INFO - Downloading blessings-1.6.tar.gz 18:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:18 INFO - Installing collected packages: blessings 18:02:18 INFO - Running setup.py install for blessings 18:02:19 INFO - Successfully installed blessings 18:02:19 INFO - Cleaning up... 18:02:19 INFO - Return code: 0 18:02:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:19 INFO - Reading from file tmpfile_stdout 18:02:19 INFO - Current package versions: 18:02:19 INFO - blessings == 1.6 18:02:19 INFO - blobuploader == 1.2.4 18:02:19 INFO - docopt == 0.6.1 18:02:19 INFO - functools32 == 3.2.3-2 18:02:19 INFO - jsonschema == 2.5.1 18:02:19 INFO - manifestparser == 1.1 18:02:19 INFO - mozInstall == 1.12 18:02:19 INFO - mozcrash == 1.0 18:02:19 INFO - mozdebug == 0.1 18:02:19 INFO - mozdevice == 0.49 18:02:19 INFO - mozfile == 1.2 18:02:19 INFO - mozhttpd == 0.7 18:02:19 INFO - mozinfo == 0.9 18:02:19 INFO - mozleak == 0.1 18:02:19 INFO - mozlog == 3.4 18:02:19 INFO - moznetwork == 0.27 18:02:19 INFO - mozprocess == 0.24 18:02:19 INFO - mozprofile == 0.28 18:02:19 INFO - mozrunner == 6.13 18:02:19 INFO - mozscreenshot == 0.1 18:02:19 INFO - mozsystemmonitor == 0.3 18:02:19 INFO - moztest == 0.8 18:02:19 INFO - mozversion == 1.4 18:02:19 INFO - psutil == 3.1.1 18:02:19 INFO - requests == 1.2.3 18:02:19 INFO - wsgiref == 0.1.2 18:02:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:19 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:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:19 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:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02:20 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:02:20 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:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:20 INFO - 'HOME': '/Users/cltbld', 18:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:20 INFO - 'LOGNAME': 'cltbld', 18:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:20 INFO - 'MOZ_NO_REMOTE': '1', 18:02:20 INFO - 'NO_EM_RESTART': '1', 18:02:20 INFO - 'PAGER': '/bin/cat', 18:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:20 INFO - 'PWD': '/builds/slave/test', 18:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:20 INFO - 'SHELL': '/bin/bash', 18:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:20 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:20 INFO - 'USER': 'cltbld', 18:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:20 INFO - 'XPC_FLAGS': '0x0', 18:02:20 INFO - 'XPC_SERVICE_NAME': '0', 18:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 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:02:20 INFO - Cleaning up... 18:02:20 INFO - Return code: 0 18:02:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02:20 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:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:20 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 0x1020c0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac92ccb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, '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.4GZtLzjwcy/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.lONOzqYzPG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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:02:20 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:02:20 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:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:20 INFO - 'HOME': '/Users/cltbld', 18:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:20 INFO - 'LOGNAME': 'cltbld', 18:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:20 INFO - 'MOZ_NO_REMOTE': '1', 18:02:20 INFO - 'NO_EM_RESTART': '1', 18:02:20 INFO - 'PAGER': '/bin/cat', 18:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:20 INFO - 'PWD': '/builds/slave/test', 18:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:20 INFO - 'SHELL': '/bin/bash', 18:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:20 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:20 INFO - 'USER': 'cltbld', 18:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:20 INFO - 'XPC_FLAGS': '0x0', 18:02:20 INFO - 'XPC_SERVICE_NAME': '0', 18:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:02:21 INFO - Cleaning up... 18:02:21 INFO - Return code: 0 18:02:21 INFO - Running post-action listener: _resource_record_post_action 18:02:21 INFO - Running post-action listener: _start_resource_monitoring 18:02:21 INFO - Starting resource monitoring. 18:02:21 INFO - [mozharness: 2017-02-10 02:02:21.161381Z] Finished create-virtualenv step (success) 18:02:21 INFO - [mozharness: 2017-02-10 02:02:21.162281Z] Running install step. 18:02:21 INFO - Running pre-action listener: _resource_record_pre_action 18:02:21 INFO - Running main action method: install 18:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:21 INFO - Reading from file tmpfile_stdout 18:02:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:21 INFO - Reading from file tmpfile_stdout 18:02:21 INFO - Output received: 18:02:21 INFO - Usage: mozinstall [options] installer 18:02:21 INFO - Options: 18:02:21 INFO - -h, --help show this help message and exit 18:02:21 INFO - -d DEST, --destination=DEST 18:02:21 INFO - Directory to install application into. [default: 18:02:21 INFO - "/builds/slave/test"] 18:02:21 INFO - --app=APP Application being installed. [default: firefox] 18:02:21 INFO - mkdir: /builds/slave/test/build/application 18:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:02:43 INFO - Reading from file tmpfile_stdout 18:02:43 INFO - Output received: 18:02:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:02:43 INFO - Running post-action listener: _resource_record_post_action 18:02:43 INFO - [mozharness: 2017-02-10 02:02:43.404685Z] Finished install step (success) 18:02:43 INFO - [mozharness: 2017-02-10 02:02:43.404807Z] Running run-tests step. 18:02:43 INFO - Running pre-action listener: _resource_record_pre_action 18:02:43 INFO - Running main action method: run_tests 18:02:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:02:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:02:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:02:43 INFO - 2017-02-09 18:02:43.419 screenresolution[1627:7457] starting screenresolution argv=screenresolution get 18:02:43 INFO - 2017-02-09 18:02:43.422 screenresolution[1627:7457] Display 0: 1600x1200x32@60 18:02:43 INFO - 2017-02-09 18:02:43.428 screenresolution[1628:7460] starting screenresolution argv=screenresolution list 18:02:43 INFO - Available Modes on Display 0 18:02:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:02:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:02:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:02:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:02:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:02:43 INFO - Intel Iris: 18:02:43 INFO - Chipset Model: Intel Iris 18:02:43 INFO - Type: GPU 18:02:43 INFO - Bus: Built-In 18:02:43 INFO - VRAM (Dynamic, Max): 1536 MB 18:02:43 INFO - Vendor: Intel (0x8086) 18:02:43 INFO - Device ID: 0x0a2e 18:02:43 INFO - Revision ID: 0x0009 18:02:43 INFO - Displays: 18:02:43 INFO - Display: 18:02:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:02:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:02:43 INFO - Display Serial Number: 1600x1200 60 18:02:43 INFO - Main Display: Yes 18:02:43 INFO - Mirror: Off 18:02:43 INFO - Online: Yes 18:02:43 INFO - Rotation: Supported 18:02:43 INFO - Return code: 0 18:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:43 INFO - Python 2.7.3 18:02:43 INFO - Return code: 0 18:02:43 INFO - grabbing minidump binary from tooltool 18:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:43 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 0x101e9ed30>, '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 0x101dc5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e93be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:43 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:02:43 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:02:43 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:02:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:43 INFO - Return code: 0 18:02:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:02:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:02:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4GZtLzjwcy/Render', 18:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:43 INFO - 'HOME': '/Users/cltbld', 18:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:43 INFO - 'LOGNAME': 'cltbld', 18:02:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:43 INFO - 'MOZ_NO_REMOTE': '1', 18:02:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:43 INFO - 'NO_EM_RESTART': '1', 18:02:43 INFO - 'PAGER': '/bin/cat', 18:02:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:43 INFO - 'PWD': '/builds/slave/test', 18:02:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:43 INFO - 'SHELL': '/bin/bash', 18:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lONOzqYzPG/Listeners', 18:02:43 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 18:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:43 INFO - 'USER': 'cltbld', 18:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:43 INFO - 'XPC_FLAGS': '0x0', 18:02:43 INFO - 'XPC_SERVICE_NAME': '0', 18:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:02:44 INFO - mozversion application_buildid: 20170209163711 18:02:44 INFO - mozversion application_changeset: 00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447 18:02:44 INFO - mozversion application_display_name: Nightly 18:02:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:44 INFO - mozversion application_name: Firefox 18:02:44 INFO - mozversion application_remotingname: firefox 18:02:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:02:44 INFO - mozversion application_vendor: Mozilla 18:02:44 INFO - mozversion application_version: 54.0a1 18:02:44 INFO - mozversion platform_buildid: 20170209163711 18:02:44 INFO - mozversion platform_changeset: 00beaa76c5b155d249bc26ab6f2c9dcf6b5d7447 18:02:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:02:44 INFO - mozversion platform_version: 54.0a1 18:02:44 INFO - using testdate: 1486692164 18:02:44 INFO - actual date: 1486692164 18:02:44 INFO - starting webserver on 'localhost:49250' 18:02:44 INFO - SUITE-START | Running 2 tests 18:02:44 INFO - TEST-START | dromaeo_css 18:02:44 INFO - operating with platform_type : mac_ 18:02:44 INFO - Initialising browser for dromaeo_css test... 18:02:44 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp0hjZGO/profile http://localhost:49250/getInfo.html) 18:02:47 INFO - PROCESS | 1638 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:02:47 INFO - PROCESS | 1638 | 2017-02-09 18:02:47.114 plugin-container[1640:7626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 18:02:47 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:47 INFO - PROCESS | 1638 | 2017-02-09 18:02:47.116 plugin-container[1640:7626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 18:02:47 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:47 INFO - PROCESS | 1638 | 2017-02-09 18:02:47.117 plugin-container[1640:7626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:47 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 18:02:47 INFO - PROCESS | 1638 | colorDepth:24 18:02:47 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 18:02:47 INFO - PROCESS | 1638 | __metrics 18:02:47 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:02:47 INFO - TEST-INFO | 1638: exit 0 18:02:47 INFO - Browser initialized. 18:02:47 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:47 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp0hjZGO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:50 INFO - PROCESS | 1641 | 2017-02-09 18:02:50.443 plugin-container[1642:7749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 18:02:50 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:50 INFO - PROCESS | 1641 | 2017-02-09 18:02:50.444 plugin-container[1642:7749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 18:02:50 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:50 INFO - PROCESS | 1641 | 2017-02-09 18:02:50.444 plugin-container[1642:7749] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:03:00 INFO - PROCESS | 1641 | RSS: Main: 234004480 18:03:00 INFO - PROCESS | 1641 | 18:06:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 18:06:04 INFO - PROCESS | 1641 | RSS: Main: 255361024 18:06:04 INFO - PROCESS | 1641 | 18:07:48 INFO - PROCESS | 1641 | ************************* 18:07:48 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 18:07:48 INFO - PROCESS | 1641 | 18:07:48 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:48 INFO - PROCESS | 1641 | Full stack: 18:07:48 INFO - PROCESS | 1641 | ************************* 18:09:06 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 18:09:07 INFO - PROCESS | 1641 | RSS: Main: 265166848 18:09:07 INFO - PROCESS | 1641 | 18:12:08 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 18:12:09 INFO - PROCESS | 1641 | RSS: Main: 276029440 18:12:09 INFO - PROCESS | 1641 | 18:15:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 18:15:12 INFO - PROCESS | 1641 | RSS: Main: 269172736 18:15:12 INFO - PROCESS | 1641 | 18:18:15 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 18:18:15 INFO - PROCESS | 1641 | RSS: Main: 274620416 18:18:15 INFO - PROCESS | 1641 | 18:21:18 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 18:21:19 INFO - PROCESS | 1641 | RSS: Main: 276107264 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | __start_tp_report 18:21:19 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 18:21:19 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 18:21:19 INFO - PROCESS | 1641 | |i|pagename|runs| 18:21:19 INFO - PROCESS | 1641 | |0;dojo.html;3252.845239939823;3265.7877237979546;3281.9999999999995;3304.947484523585;3308.718758905493;5330.3603567571945;5427.267318911946;5433.646812957155;5443.972780136104;5534;5067.366579177612;5335.733213339327;5430.5384042356445;5454.890902181954;5527.060399732045;3716.739828212026;4950.356453661037;5104.923426148611;5153.69077855328;5159.303494028296;4924.162892308316;5127.641065125443;5171;5176.7929282828645;5194.766235519411;4903.509649035103;4958.727270000149;5081.669691470042;5087.720175390352;5166.07010738067;4988.326575912241;5019.121653710586;5031.572316353115;5065.822696205615;5121.615878809067;4999.250112483121;5016.573591244749;5018.247262910556;5036.471170527078;5085.1355269604255;4516.774161291923;5275.472452754732;5311.973440132813;5448.972755136238;5511.669299841982;5804.88390232193;5960.850978725523;6008.48927841134;6027.457528822383;6140.723667434976;5226.7717086484645;5936.940630593682;6125.816225513242;6161.845953851145;6163.445289923883;4956.4547899731;5360.579446446694;5623.0721930881355;5643.040683083886;5645.15322701594;5517.641353312022;5538.698405874617;6006.339302676702;6119.969400152971;6166.475849552839;5922.3485416604135;5931.940680593225;5990.73041713124;6027.7287522061615;6045.516358691294;6123.663198524124;6138.631682099089;6141.109539116893;6144.907826382608;6154.476869466056;5267.525922666979;5769.221155144001;5793.391693872167;5959.73181206838;6002.939970600238;6072.726727297282;6075.817725468243;6093.086037094383;6104.694765261719;6126.479249263863;4836.758162091881;4873.951260487421;4888.413390393104;4892.510748925079;4932.087563800636;4304.526502084831;4308.052228509723;4325.437693099877;4343.565643435694;4379.167958088017;5624.634398764068;5632.352279487829;5643.548516118701;5659.745311460994;5692;5729.8567535811935;6081.604695694854;6140.416660417253;6154.969225153845;6155.261368635793;5392.191171324254;5438.15708565173;5555.666660000413;5651.208830763697;5651.802186923438;5273.182656688219;5321.521063104339;5339.252504649352;5372.892542149213;5377.650452720561;6221.720022598994;6282.874342513124;6284.685765711604;6287.937120628827;6305.274893387319;5869.7945571904775;5965.791197308074;6125.9693701531205;6165.414285642857;6168.136460895388;5167.638265321391;5322.654027488201;6200.286966998853;6273.215848018997;6291;6007.4893634039845;6034.487068599043;6049.243844519435;6062.4240697135465;6095.817125486243;5791.884162316645;6134.539909506716;6137.7851775187655;6153.938460615336;6164.291106522807;4954.77703503336;5307.65500242483;5847.649141051551;5907.940920590911;5994.640321580545;4837.637177211653;5847.970760146342;5913.201717768046;5921.822345329475;5924.822255332347;5210.557102646318;5567.944320556742;5609.102543593006;5773.595848290579;5783.884322313446;5455.9454405455435;5937.851553711192;5981.461668449861;5991.550633702403;6021.969890150521;4767.237242041257;5201.895962080813;5751.971240143773;5793.565482588907;5804.9709751452665 18:21:19 INFO - PROCESS | 1641 | |1;ext.html;434.3636572421403;479.5108988831393;479.7313504437513;540.3380858448401;570.7659859457623;28775.71224287759;37511;37590.624093759056;38918.80540597293;38955;31811.522827157612;39994.200115997664;40107.39838902419;40197.59802401975;40244.79877600615;8538.786530336745;9027.593758280893;9066.909330906705;9083.591238394256;9163.450192988408;8876.733697989072;8988.056254093324;8994.415363001417;9005;9021.909780902173;8794.472331660121;8954.641814327419;8967.865482017774;8985.415947963395;8996.8650470243;8876.644934202624;8894.911050889505;8951.820963580756;8960.1935825776;8985.460872347614;8812.339074569432;8844.823103537958;8914.86627700585;8915.375923685344;8921.464712117293;35410.29179416423;35708;35719.82140089309;35759.64240357589;35761.46357804635;3764.9811750941344;3779.074126838942;3809.847606095753;3822.694184465236;3923.88228353147;26953;27396.45207095867;27535;27655.861720691468;27807.721922780715;3013.909582712522;3072.201227680797;3211.0687900508824;3246.2858171202297;3249.675032496755;436389;437920;441386;442082.78958605323;442607;46960.06079878383;51028.489715102376;51453.71365715887;51600.48399515957;51656.70858228574;56046.71976640172;56285.718571406884;56325.71837140788;56475.870482590115;56546.717266413405;13540.864591354157;14009;14012.509562165274;14017.158970461805;14024.158550487004;11019.173561982883;11149.609763658233;11204.495797689124;11216.439178041066;11217.439128043565;16460.83539164593;17179.82820171807;17191.484255472355;17239.913800430917;17243.913780431016;6865.450763938873;7064.364207221272;7756;7784.455088143889;7811.843763124706;833.2833762963846;838.2455789789238;855.0978717453022;864.5072308783933;869.9347548933855;26128.216153515426;28187;28214.85892570524;28969.56545651817;29820.403591928472;1004.8492726091145;1033.7674023344837;1045.2056437107701;1058.1217589400778;1170.3913964738288;14255.857441425453;14717.484888028868;14990.550283491513;15046.699066018837;15179.306936130555;545470;547728;550243;550576.2125461833;552192;56432;56738.7163064182;57515.13727294094;57531;57554.42445575575;1304.1262354222692;1349.0691422918155;1351.9729605408033;1357.5316515802033;1373.663452454155;25019;26718;27147;28157.43685126245;28250.576241355986;36528.63471365252;42863.71408857739;43237.13525729405;43339.349909750745;43472.56527434685;427719;431690;432150;432310;433956;24179.15372961938;24609.630855536823;24647.753522465264;24659.630105548073;24678.506429870944;33721.662783372834;41128;42408.575914241694;43056;44384.55615443804;33918.66081339155;34022.14944626404;34842.47736283907;35093.94718158459;35477.82261088678;33962.320753584296;34006.82996585001;34848;35341.469877951335;35574;33142;33258.00225993224;33491.834606499346;33562.664373355954;34696.479552807235;29298.560521591768;30926.536101958038;30952.535711963887;30998.22504437317;31092.844535777178;22487.437814054127;22700.432489187242;23383.298501044996;23755.64366534538;23934.640980385655 18:21:19 INFO - PROCESS | 1641 | |2;jquery.html;379.3494157519855;381.046330011267;392.68388946897744;396.5637798421736;413.86394347516074;18216.271349146034;18792;19333.80666193336;19378.418647440583;19551.02244887757;20211.494712632193;20551.897240513776;20684.27605033824;20751.169953201854;20931.058102385392;5432.67403955762;5454.181872719084;5578.944210557883;5645.576581756364;5662.6036177467595;5546.500814926656;5643.266375371196;5650.378458369576;5653.830385088454;5670.4896559309655;5432.093839770181;5444.50999410053;5571.526420254283;5581.5255703265275;5592.552595792326;5332.9733351333;5521.779128834842;5523.392426833045;5580.637258578179;5593.720313984305;4844.176489996709;4928.778204980785;4930.309756634074;4937.407511098655;4957.5042495750495;18380.059190529584;19704.70442943357;23660.526789464286;24317.513649727083;24343.634845477332;2625.2911713837157;2630.736926307373;2633.868306584682;2657.973420265792;2697.2178068360154;14870.851291487055;15386.692266154727;15472.303746331441;15558.844411555852;16027.679446411006;1799.4601619514224;1821.5172979160534;1827.3056238629235;1828.1133650179786;1828.6342731453763;86549;98326;113286.43356783246;121386.57226855501;123259.38370308091;7346.669399877019;8385.371097167737;10629.25595208328;10634.384309334864;10928.672139835817;11220.214584979136;12799.29603871796;12905.806412903814;12940.870591294155;12954.740905181843;7568.091828980558;7750.496217745828;7768.922310776933;7805.453618246782;7856.528608283521;6314.557980941382;7663.118741344705;7674.8081297968;7775.22247775218;7836.393539324425;4034.172994536113;4131.153113611703;4133;4193.035601811601;4194.853180138681;2015.4256037029377;2058.341330774138;2070.730804995341;2071.616750901088;2076.1591555420077;786.8780339047457;1010.7978404319166;1014.8738393066513;1035.6012935020071;1049.490996866519;19213.711794323095;20683.896580517;20706.896465517577;20735.481612959797;20808.27171049006;899.3659470073583;1029.6910926721953;1031.3648627756006;1058.899404556566;1064.8775390829999;10515.737106572244;10843.349399036084;10861.511231994578;10892.455377231106;10912;409209;490360;570331;658457;659528;131369;140976;146745.26627366795;148823.25588372204;155498;1001.47923079998;1017.3387298256044;1043.2488608202082;1050.3750268590177;1055.2982266792496;14973.475928342456;17944.730829037835;18010.72983905269;19415.126319315947;19552.511187220432;44468.55531444644;46588.83527911694;52219.47780522298;54219.72890135524;54600.18099728587;64393.746218882115;67082;73178;76323.03900246466;81634.59182704048;5813.418658134153;6915.550489218184;8186.222308880647;8312.750617481484;8368.707095251637;7459.067616547932;9580.95209523948;11990;12152.93923530412;12857.807132893202;11289.830652540055;11328.093752499944;11487.655370338904;11512.424378781196;11748.883856033663;9251.259899207913;10112.24158188124;10262.846057309296;11165.050970667584;11490;10663.840042399215;10878.619248326271;11212.103031757277;11554.248973816675;11691.356975366436;5640.830775076754;7430.851382972202;7746.92253077462;7846.5292082475235;8582.742517724479;9857;10963.890361096286;11101.888981110085;11420;11517.53929842797 18:21:19 INFO - PROCESS | 1641 | |3;mootools.html;1207.516993202719;1223.4584423626231;1388.1670997401566;1398.2589227709318;1412.7174565086984;8036.075851277104;8252.628631711572;8393.328533717304;8555.657773689061;8568.400211985163;8419.452735572198;8571.571421428935;8579.742607721762;8587;8601.612927418266;2258.3337915315024;2285.817134629234;2295.093438091961;2303.665968434576;2315.0045480443405;2249.8425110242288;2315.976840231593;2316.270374831931;2325.5116425550655;2329.5457385809773;2261.6720575516542;2269.8751568663715;2282.9086836526517;2283.4405570635136;2294.678744975705;2215.235743668432;2249.606318894187;2263.852849564781;2288.1533832482087;2291.049214575949;1981.4352909420745;2061.9587608247753;2225.4658881868395;2268.160780511205;2283.8515496492673;7043.788686339418;8169.8970638964165;8350.665973361058;8362.581870906492;8401.949756280464;5041.991601679679;5160.716160611165;5179.430262671103;5193.584513238932;5206.947930520684;3279.6064472263242;3308.470644696837;3375.1899544109483;3377.3751855906735;3382.780119292238;975.5024937281967;1184.3012622552649;1231.7536492701495;1242.2608547913976;1675.4889758623615;10574.889636588125;10585.73535661607;10872.347659140476;10901.890981090168;10925.180611454094;4249.660027197817;4466.196084704784;4480.305552639281;4510.75190864499;4548.886277843014;7371.226021267797;7409.518381305198;8790.077041910636;8816.250618697482;8862.64549418029;4237.851675191354;5113.411957624847;5113.795448182105;5130.820421285237;5139.7687104080405;4572.3827283317;4643.72137671734;4676.438827340741;4708.599769019672;4725.243960966229;3630.8003059831435;3661.139632186434;3684.8526058957877;3725.2363265530503;3726.6832319252626;1169.4094482286391;1169.6432588060723;1172.3083380805365;1174.395186478964;1184.283508477376;713.6859781696037;718.7448455798221;738.1437532462318;744.5421066044344;763.023330137425;5337.6797392156595;5519.586031047687;5629.268195134607;5657.915131273035;5709.486146246858;544.1891581543472;547.8411260734422;569.6126633888995;575.7092668202531;577.2207519848172;3682.208325210093;3727.471736587986;3769.509963704701;3872.283627528916;3903.6291552302487;9619.759506012266;9909.256805739598;9966.2525310602;9967.601295948225;9981.151602113756;9814;9858.704238872846;9906.900930990598;9920.751981200528;9926.602935882484;7578.924210757932;7595.392368610498;7764.534127952341;7838.294553490327;7848.686052557948;4503.176213633484;4573.5426457354;5512.724363781715;5619.353774316006;5650.858728531655;8822.426542274732;9019.503927284062;9091.863622045543;9205.907940920504;9240.122188392092;9009.459432434074;9164.358494905558;9304.674336398193;9309.162175404246;9312.627494900398;6966.303369663196;7083.82290442743;7207.639618019183;7217.566945983258;7234.421246300178;7927.009123859518;8059.597020148852;8087.353011759163;8094;8095.676172953016;7151.141862976476;7254.056972593529;7275.454340924558;7298.854022919618;7370.152432470334;5973.372795856372;6217.253929528486;6858.491131950963;7128.501004929605;7135.714571417086;5601.075822489406;6316.273628532777;6375.330590287953;6430.903536446864;6434.163558737334;4916.827911023097;5004.649674522749;5019;5101.132807422821;5107.87230319245;4943;4983.501649834988;5078.441371449211;5174.482551744795;5181.637285390138 18:21:19 INFO - PROCESS | 1641 | |4;prototype.html;344.2203409277984;352.64030688697545;358.4014695458584;365.4262807392392;365.7604269203672;4197.321071571373;5173.490856934393;5315.920261196085;5492.5605951523885;5503.679117011919;4418.801153948072;4695.718256904588;5253.212018197272;5426.2674538937235;5428.669975855921;4262.3393374027;4290.721103128302;4294.119705460389;4296.291111966521;4319.935200971988;3682.4108142697173;3978.661813745835;4048.9797551012352;4137.8758637240935;4318.956810431887;3661.2311414602964;4203.264428724976;4204.5375008749015;4224.387463817745;4236.639885609711;4233.407322974786;4233.894152646193;4244.766537840417;4260.659147268211;4285.5928686774705;3548.1484443733316;3743;3771.585125636178;3779;3788.507494025759;4748.881277968044;5044.268581055763;5180.766865491062;5260.947390526084;5272.1300985337375;4279.994201362677;4897.706137631718;4918.704877707349;4924.301116114934;4943.431505376856;5424.911409107522;5772.826815195551;5790.901867500909;5793.4785869271545;5798.681072541009;3543;3934.9803250983564;4096.40602112692;4132.235536425741;4148.294789885696;4797.040591881638;5681.091025435951;5813.273340832438;6022.163240211734;6077.24034495688;3497.1257185703575;4163.666906647461;4225.302825033835;4226.971053894148;4229.788510574459;3824.4072168813877;4243.342281946303;4278.55075217104;4279.807408666617;4304.870853874389;4060.3914556978043;4471.731696098244;4922.581580565692;4931.753412329441;4933.629977751683;5747.540196784247;5818.418158184148;5821.679807610537;5828.522877776928;5855.00464920956;1237.8920865825037;1267.6957530192872;1408.9855304181178;1410.971780564383;1412.5479846449039;832.937529685276;969.4231932000448;977.7213494154274;987.693814917378;988.125508924607;4720.787564559603;5158.561522270649;5212.582993360522;5214.757654208726;5222.556082732935;5010.094145395662;5242.7378631068295;5374.785008599691;5661.772789510022;5768.9711551442815;4340.784580317453;5019.899602007939;5212.722882893715;5224.346956630421;5224.891189742098;2605.4919290738503;2635.314818147257;2859.8856045758353;2989.40211957605;3019.8037127586636;4916.475892473345;5712.886232094152;5910.468057874726;5948.46066590378;5948.95893218685;4801.159797035505;5286.3392075990505;5750.971245143747;5958.404159584007;6150.323464418911;5221.903400285983;5403.972980135074;5627.971860140673;6139.47814435769;6180.47465965398;5161.7677204525135;5203.947960520346;5560.416156303691;5942.61373010753;6042.395760423922;5505.25642563061;5888.852778680567;5893.381194974466;5924.318703348998;5936.940630593812;5370.516653501204;5374.570034397317;5608.261438953938;5986.132010858314;6172.506199503939;3432.987268755673;3683.3001729671278;3721.5534135903863;3931.4692516509904;3985.701072419638;5244.632875698664;5402.540784033401;5460.907818436249;5938.792142275;5950.226470558973;4983.426905905722;5123.0020292086865;5442.6462279951675;5561.944380556304;5750.281214848144;5024.899502010013;5425.430329815313;5687.146927960839;5725.652482482329;5769.951667158099;4995.300657907823;5119.668886089569;5630.5214056805635;5662.489256660732;5663.32040155184;4830.975845120752;5470.292205358373;5541.473560011793;5585.357683866245;5773.278340207474;5201.687898726089;5594.915711530524;5607.186957891165;5637.449701332166;5649.802256920988 18:21:19 INFO - PROCESS | 1641 | |5;yui.html;664.8105289992353;665.6838001949071;677.7872882536777;694.3785312145629;765.7970637780985;14051.789223161648;14633.63415914604;15030.02304850184;15132.84867151331;15245.161516116608;13603.38784754686;13756.24875024994;13900.513482028131;13954.372053257603;13987;847.5423271433434;859.1751918158544;861.6381119929645;861.7974775927652;863.3093525179862;840.3361344537808;860.4106187261715;870.0212261206143;877.0440220160025;879.0989236033085;899.4108858697564;909.9590518426687;910.3672947301628;912.2428384440898;914.5381582300929;896.7175339767953;902.5261737587754;909.3725329522641;910.6311943662827;913.4336711239041;661.0579923608839;675.2909445082693;675.7668604331498;676.8206425297251;677.4478799778215;14177.43290268388;14204.928975355057;14947.40210391583;15103.244837758068;15143.772843407247;13645.590632281048;13769.862301376957;13945.790813137812;13952.302384880817;13985.3706583204;11350.262232954832;11501.798247145523;11645.70885727855;11990;12133.575324863587;3443.328550932569;3486.5467489226494;3496.5104885315823;3499.6325385834375;3620.348337299285;102149.489252554;102446;102921;103171.4841425788;104186;10440.321379110486;10463.215258855464;10669.253152279422;10682;10766.24636275469;21433.678494822278;21943.780562194494;21962.231321903655;22044.33866983993;22660.320190394315;7367.8894816577795;7475.102987641518;7528.134264559646;7542.924570754332;7800.648970796215;4092.4475195848777;4167.1457351242925;4203.432536607519;4203.789810509462;4204;3454.4127498325347;3698.4452332150395;3700.833462494194;3725.720570957189;3739.8691045813266;677.5392732941647;678.7658257901016;679.9456043516606;688.6797639097844;693.9375456208966;587.224863180606;636.4144986612328;637.0666972884653;697.1808125452573;699.2902204262678;9596.95201523988;9928.751781205528;9933.205343572497;9965.750856228507;10144.746381340377;543.6466296906963;584.5294537896949;588.7821506042792;616.7255571270831;644.4489961083207;4095.3037983542868;4540.205464043779;4630.236011058205;4645.233536466445;4816.8795780105775;68143.65928170328;79216;80124;80994.5950270245;88127.1187288119;38529.84410467691;65583.34416655867;68350;68848.6557567219;74440.25559744441;56765.71617141971;57923;66223;68783;70767;8608.09614990417;10014.017048296753;10085.949570252102;10562.690492882562;10687.732806679895;33387.16532086717;42913.57086429096;43977.56022439735;44492.55507445014;44540.77729611331;46562.76718616385;61588.76822463615;62084;62758.372416275255;63459.730805384555;12129.029677625946;12335.876641233473;12849.421776019915;13003.609891703263;13086.803697944348;13773.755117821276;14294.857051429768;14524.49164279245;14740.484083057041;14780.704385912437;13133.671658208621;13480.325983700972;13598.93200533991;13842.930785346009;13984.021118521607;13312.267825269702;13528.255945922676;13660.043796934517;13859.79210311826;14020.859791401956;13063.542776002794;13275;13396.464141434233;13661.726765464833;13866.584002479942;10601.840972385575;10735;10864.168106615709;10881.673549793519;11037.613683521038;8887.677208287792;9253.629854806002;10022.047905448972;10256.38461692301;10257.974202579682 18:21:19 INFO - PROCESS | 1641 | __end_tp_report 18:21:19 INFO - PROCESS | 1641 | __start_cc_report 18:21:19 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,406 18:21:19 INFO - PROCESS | 1641 | __end_cc_report 18:21:19 INFO - PROCESS | 1641 | __startTimestamp1486693279004__endTimestamp 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | ------- Summary: start ------- 18:21:19 INFO - PROCESS | 1641 | Number of tests: 6 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5511.47 Median:5632.35 stddev:644.95 (11.5%) stddev-sans-first:622.11 18:21:19 INFO - PROCESS | 1641 | Values: 3252.8 3265.8 3282.0 3304.9 3308.7 5330.4 5427.3 5433.6 5444.0 5534.0 5067.4 5335.7 5430.5 5454.9 5527.1 3716.7 4950.4 5104.9 5153.7 5159.3 4924.2 5127.6 5171.0 5176.8 5194.8 4903.5 4958.7 5081.7 5087.7 5166.1 4988.3 5019.1 5031.6 5065.8 5121.6 4999.3 5016.6 5018.2 5036.5 5085.1 4516.8 5275.5 5312.0 5449.0 5511.7 5804.9 5960.9 6008.5 6027.5 6140.7 5226.8 5936.9 6125.8 6161.8 6163.4 4956.5 5360.6 5623.1 5643.0 5645.2 5517.6 5538.7 6006.3 6120.0 6166.5 5922.3 5931.9 5990.7 6027.7 6045.5 6123.7 6138.6 6141.1 6144.9 6154.5 5267.5 5769.2 5793.4 5959.7 6002.9 6072.7 6075.8 6093.1 6104.7 6126.5 4836.8 4874.0 4888.4 4892.5 4932.1 4304.5 4308.1 4325.4 4343.6 4379.2 5624.6 5632.4 5643.5 5659.7 5692.0 5729.9 6081.6 6140.4 6155.0 6155.3 5392.2 5438.2 5555.7 5651.2 5651.8 5273.2 5321.5 5339.3 5372.9 5377.7 6221.7 6282.9 6284.7 6287.9 6305.3 5869.8 5965.8 6126.0 6165.4 6168.1 5167.6 5322.7 6200.3 6273.2 6291.0 6007.5 6034.5 6049.2 6062.4 6095.8 5791.9 6134.5 6137.8 6153.9 6164.3 4954.8 5307.7 5847.6 5907.9 5994.6 4837.6 5848.0 5913.2 5921.8 5924.8 5210.6 5567.9 5609.1 5773.6 5783.9 5455.9 5937.9 5981.5 5991.6 6022.0 4767.2 5201.9 5752.0 5793.6 5805.0 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:60067.39 Median:20677.80 stddev:127007.39 (614.2%) stddev-sans-first:127284.76 18:21:19 INFO - PROCESS | 1641 | Values: 434.4 479.5 479.7 540.3 570.8 28775.7 37511.0 37590.6 38918.8 38955.0 31811.5 39994.2 40107.4 40197.6 40244.8 8538.8 9027.6 9066.9 9083.6 9163.5 8876.7 8988.1 8994.4 9005.0 9021.9 8794.5 8954.6 8967.9 8985.4 8996.9 8876.6 8894.9 8951.8 8960.2 8985.5 8812.3 8844.8 8914.9 8915.4 8921.5 35410.3 35708.0 35719.8 35759.6 35761.5 3765.0 3779.1 3809.8 3822.7 3923.9 26953.0 27396.5 27535.0 27655.9 27807.7 3013.9 3072.2 3211.1 3246.3 3249.7 436389.0 437920.0 441386.0 442082.8 442607.0 46960.1 51028.5 51453.7 51600.5 51656.7 56046.7 56285.7 56325.7 56475.9 56546.7 13540.9 14009.0 14012.5 14017.2 14024.2 11019.2 11149.6 11204.5 11216.4 11217.4 16460.8 17179.8 17191.5 17239.9 17243.9 6865.5 7064.4 7756.0 7784.5 7811.8 833.3 838.2 855.1 864.5 869.9 26128.2 28187.0 28214.9 28969.6 29820.4 1004.8 1033.8 1045.2 1058.1 1170.4 14255.9 14717.5 14990.6 15046.7 15179.3 545470.0 547728.0 550243.0 550576.2 552192.0 56432.0 56738.7 57515.1 57531.0 57554.4 1304.1 1349.1 1352.0 1357.5 1373.7 25019.0 26718.0 27147.0 28157.4 28250.6 36528.6 42863.7 43237.1 43339.3 43472.6 427719.0 431690.0 432150.0 432310.0 433956.0 24179.2 24609.6 24647.8 24659.6 24678.5 33721.7 41128.0 42408.6 43056.0 44384.6 33918.7 34022.1 34842.5 35093.9 35477.8 33962.3 34006.8 34848.0 35341.5 35574.0 33142.0 33258.0 33491.8 33562.7 34696.5 29298.6 30926.5 30952.5 30998.2 31092.8 22487.4 22700.4 23383.3 23755.6 23934.6 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:33573.96 Median:20869.66 stddev:95171.39 (456.0%) stddev-sans-first:95405.73 18:21:19 INFO - PROCESS | 1641 | Values: 379.3 381.0 392.7 396.6 413.9 18216.3 18792.0 19333.8 19378.4 19551.0 20211.5 20551.9 20684.3 20751.2 20931.1 5432.7 5454.2 5578.9 5645.6 5662.6 5546.5 5643.3 5650.4 5653.8 5670.5 5432.1 5444.5 5571.5 5581.5 5592.6 5333.0 5521.8 5523.4 5580.6 5593.7 4844.2 4928.8 4930.3 4937.4 4957.5 18380.1 19704.7 23660.5 24317.5 24343.6 2625.3 2630.7 2633.9 2658.0 2697.2 14870.9 15386.7 15472.3 15558.8 16027.7 1799.5 1821.5 1827.3 1828.1 1828.6 86549.0 98326.0 113286.4 121386.6 123259.4 7346.7 8385.4 10629.3 10634.4 10928.7 11220.2 12799.3 12905.8 12940.9 12954.7 7568.1 7750.5 7768.9 7805.5 7856.5 6314.6 7663.1 7674.8 7775.2 7836.4 4034.2 4131.2 4133.0 4193.0 4194.9 2015.4 2058.3 2070.7 2071.6 2076.2 786.9 1010.8 1014.9 1035.6 1049.5 19213.7 20683.9 20706.9 20735.5 20808.3 899.4 1029.7 1031.4 1058.9 1064.9 10515.7 10843.3 10861.5 10892.5 10912.0 409209.0 490360.0 570331.0 658457.0 659528.0 131369.0 140976.0 146745.3 148823.3 155498.0 1001.5 1017.3 1043.2 1050.4 1055.3 14973.5 17944.7 18010.7 19415.1 19552.5 44468.6 46588.8 52219.5 54219.7 54600.2 64393.7 67082.0 73178.0 76323.0 81634.6 5813.4 6915.6 8186.2 8312.8 8368.7 7459.1 9581.0 11990.0 12152.9 12857.8 11289.8 11328.1 11487.7 11512.4 11748.9 9251.3 10112.2 10262.8 11165.1 11490.0 10663.8 10878.6 11212.1 11554.2 11691.4 5640.8 7430.9 7746.9 7846.5 8582.7 9857.0 10963.9 11101.9 11420.0 11517.5 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5344.59 Median:5272.31 stddev:2951.68 (56.0%) stddev-sans-first:2943.58 18:21:19 INFO - PROCESS | 1641 | Values: 1207.5 1223.5 1388.2 1398.3 1412.7 8036.1 8252.6 8393.3 8555.7 8568.4 8419.5 8571.6 8579.7 8587.0 8601.6 2258.3 2285.8 2295.1 2303.7 2315.0 2249.8 2316.0 2316.3 2325.5 2329.5 2261.7 2269.9 2282.9 2283.4 2294.7 2215.2 2249.6 2263.9 2288.2 2291.0 1981.4 2062.0 2225.5 2268.2 2283.9 7043.8 8169.9 8350.7 8362.6 8401.9 5042.0 5160.7 5179.4 5193.6 5206.9 3279.6 3308.5 3375.2 3377.4 3382.8 975.5 1184.3 1231.8 1242.3 1675.5 10574.9 10585.7 10872.3 10901.9 10925.2 4249.7 4466.2 4480.3 4510.8 4548.9 7371.2 7409.5 8790.1 8816.3 8862.6 4237.9 5113.4 5113.8 5130.8 5139.8 4572.4 4643.7 4676.4 4708.6 4725.2 3630.8 3661.1 3684.9 3725.2 3726.7 1169.4 1169.6 1172.3 1174.4 1184.3 713.7 718.7 738.1 744.5 763.0 5337.7 5519.6 5629.3 5657.9 5709.5 544.2 547.8 569.6 575.7 577.2 3682.2 3727.5 3769.5 3872.3 3903.6 9619.8 9909.3 9966.3 9967.6 9981.2 9814.0 9858.7 9906.9 9920.8 9926.6 7578.9 7595.4 7764.5 7838.3 7848.7 4503.2 4573.5 5512.7 5619.4 5650.9 8822.4 9019.5 9091.9 9205.9 9240.1 9009.5 9164.4 9304.7 9309.2 9312.6 6966.3 7083.8 7207.6 7217.6 7234.4 7927.0 8059.6 8087.4 8094.0 8095.7 7151.1 7254.1 7275.5 7298.9 7370.2 5973.4 6217.3 6858.5 7128.5 7135.7 5601.1 6316.3 6375.3 6430.9 6434.2 4916.8 5004.6 5019.0 5101.1 5107.9 4943.0 4983.5 5078.4 5174.5 5181.6 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4620.32 Median:5177.13 stddev:1382.31 (26.7%) stddev-sans-first:1348.41 18:21:19 INFO - PROCESS | 1641 | Values: 344.2 352.6 358.4 365.4 365.8 4197.3 5173.5 5315.9 5492.6 5503.7 4418.8 4695.7 5253.2 5426.3 5428.7 4262.3 4290.7 4294.1 4296.3 4319.9 3682.4 3978.7 4049.0 4137.9 4319.0 3661.2 4203.3 4204.5 4224.4 4236.6 4233.4 4233.9 4244.8 4260.7 4285.6 3548.1 3743.0 3771.6 3779.0 3788.5 4748.9 5044.3 5180.8 5260.9 5272.1 4280.0 4897.7 4918.7 4924.3 4943.4 5424.9 5772.8 5790.9 5793.5 5798.7 3543.0 3935.0 4096.4 4132.2 4148.3 4797.0 5681.1 5813.3 6022.2 6077.2 3497.1 4163.7 4225.3 4227.0 4229.8 3824.4 4243.3 4278.6 4279.8 4304.9 4060.4 4471.7 4922.6 4931.8 4933.6 5747.5 5818.4 5821.7 5828.5 5855.0 1237.9 1267.7 1409.0 1411.0 1412.5 832.9 969.4 977.7 987.7 988.1 4720.8 5158.6 5212.6 5214.8 5222.6 5010.1 5242.7 5374.8 5661.8 5769.0 4340.8 5019.9 5212.7 5224.3 5224.9 2605.5 2635.3 2859.9 2989.4 3019.8 4916.5 5712.9 5910.5 5948.5 5949.0 4801.2 5286.3 5751.0 5958.4 6150.3 5221.9 5404.0 5628.0 6139.5 6180.5 5161.8 5203.9 5560.4 5942.6 6042.4 5505.3 5888.9 5893.4 5924.3 5936.9 5370.5 5374.6 5608.3 5986.1 6172.5 3433.0 3683.3 3721.6 3931.5 3985.7 5244.6 5402.5 5460.9 5938.8 5950.2 4983.4 5123.0 5442.6 5561.9 5750.3 5024.9 5425.4 5687.1 5725.7 5770.0 4995.3 5119.7 5630.5 5662.5 5663.3 4831.0 5470.3 5541.5 5585.4 5773.3 5201.7 5594.9 5607.2 5637.4 5649.8 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:18078.96 Median:4093.88 stddev:24676.90 (602.8%) stddev-sans-first:24711.48 18:21:19 INFO - PROCESS | 1641 | Values: 664.8 665.7 677.8 694.4 765.8 14051.8 14633.6 15030.0 15132.8 15245.2 13603.4 13756.2 13900.5 13954.4 13987.0 847.5 859.2 861.6 861.8 863.3 840.3 860.4 870.0 877.0 879.1 899.4 910.0 910.4 912.2 914.5 896.7 902.5 909.4 910.6 913.4 661.1 675.3 675.8 676.8 677.4 14177.4 14204.9 14947.4 15103.2 15143.8 13645.6 13769.9 13945.8 13952.3 13985.4 11350.3 11501.8 11645.7 11990.0 12133.6 3443.3 3486.5 3496.5 3499.6 3620.3 102149.5 102446.0 102921.0 103171.5 104186.0 10440.3 10463.2 10669.3 10682.0 10766.2 21433.7 21943.8 21962.2 22044.3 22660.3 7367.9 7475.1 7528.1 7542.9 7800.6 4092.4 4167.1 4203.4 4203.8 4204.0 3454.4 3698.4 3700.8 3725.7 3739.9 677.5 678.8 679.9 688.7 693.9 587.2 636.4 637.1 697.2 699.3 9597.0 9928.8 9933.2 9965.8 10144.7 543.6 584.5 588.8 616.7 644.4 4095.3 4540.2 4630.2 4645.2 4816.9 68143.7 79216.0 80124.0 80994.6 88127.1 38529.8 65583.3 68350.0 68848.7 74440.3 56765.7 57923.0 66223.0 68783.0 70767.0 8608.1 10014.0 10085.9 10562.7 10687.7 33387.2 42913.6 43977.6 44492.6 44540.8 46562.8 61588.8 62084.0 62758.4 63459.7 12129.0 12335.9 12849.4 13003.6 13086.8 13773.8 14294.9 14524.5 14740.5 14780.7 13133.7 13480.3 13598.9 13842.9 13984.0 13312.3 13528.3 13660.0 13859.8 14020.9 13063.5 13275.0 13396.5 13661.7 13866.6 10601.8 10735.0 10864.2 10881.7 11037.6 8887.7 9253.6 10022.0 10256.4 10258.0 18:21:19 INFO - PROCESS | 1641 | -------- Summary: end -------- 18:21:19 INFO - PROCESS | 1641 | 18:21:19 INFO - TEST-INFO | 1641: exit 0 18:21:19 INFO - TEST-OK | dromaeo_css | took 1115739ms 18:21:19 INFO - TEST-START | kraken 18:21:19 INFO - operating with platform_type : mac_ 18:21:19 INFO - Initialising browser for kraken test... 18:21:19 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmptrhuuK/profile http://localhost:49250/getInfo.html) 18:21:22 INFO - PROCESS | 1653 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:21:22 INFO - PROCESS | 1653 | 2017-02-09 18:21:22.316 plugin-container[1654:10140] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 18:21:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:22 INFO - PROCESS | 1653 | 2017-02-09 18:21:22.319 plugin-container[1654:10140] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 18:21:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:22 INFO - PROCESS | 1653 | 2017-02-09 18:21:22.319 plugin-container[1654:10140] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:22 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 18:21:22 INFO - PROCESS | 1653 | colorDepth:24 18:21:22 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 18:21:22 INFO - PROCESS | 1653 | __metrics 18:21:22 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:21:23 INFO - TEST-INFO | 1653: exit 0 18:21:23 INFO - Browser initialized. 18:21:23 INFO - Running cycle 1/1 for kraken test... 18:21:23 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmptrhuuK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:25 INFO - PROCESS | 1655 | 2017-02-09 18:21:25.650 plugin-container[1656:10258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.tsm.portname' 18:21:25 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:25 INFO - PROCESS | 1655 | 2017-02-09 18:21:25.650 plugin-container[1656:10258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.CFPasteboardClient' 18:21:25 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:25 INFO - PROCESS | 1655 | 2017-02-09 18:21:25.651 plugin-container[1656:10258] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:35 INFO - PROCESS | 1655 | RSS: Main: 234090496 18:21:35 INFO - PROCESS | 1655 | 18:21:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:39 INFO - PROCESS | 1655 | RSS: Main: 247730176 18:21:39 INFO - PROCESS | 1655 | 18:21:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 18:21:42 INFO - PROCESS | 1655 | RSS: Main: 255488000 18:21:42 INFO - PROCESS | 1655 | 18:21:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 18:21:46 INFO - PROCESS | 1655 | RSS: Main: 257867776 18:21:46 INFO - PROCESS | 1655 | 18:21:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 18:21:49 INFO - PROCESS | 1655 | RSS: Main: 260235264 18:21:49 INFO - PROCESS | 1655 | 18:21:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:51 INFO - PROCESS | 1655 | RSS: Main: 260620288 18:21:51 INFO - PROCESS | 1655 | 18:21:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:55 INFO - PROCESS | 1655 | RSS: Main: 266342400 18:21:55 INFO - PROCESS | 1655 | 18:21:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:58 INFO - PROCESS | 1655 | RSS: Main: 265371648 18:21:58 INFO - PROCESS | 1655 | 18:22:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 18:22:02 INFO - PROCESS | 1655 | RSS: Main: 265420800 18:22:02 INFO - PROCESS | 1655 | 18:22:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:04 INFO - PROCESS | 1655 | RSS: Main: 264773632 18:22:04 INFO - PROCESS | 1655 | 18:22:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:07 INFO - PROCESS | 1655 | RSS: Main: 264810496 18:22:07 INFO - PROCESS | 1655 | 18:22:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:10 INFO - PROCESS | 1655 | RSS: Main: 265142272 18:22:10 INFO - PROCESS | 1655 | 18:22:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:14 INFO - PROCESS | 1655 | RSS: Main: 266121216 18:22:14 INFO - PROCESS | 1655 | 18:22:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:18 INFO - PROCESS | 1655 | RSS: Main: 265715712 18:22:18 INFO - PROCESS | 1655 | 18:22:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 18:22:20 INFO - PROCESS | 1655 | RSS: Main: 267776000 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | __start_tp_report 18:22:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 18:22:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 18:22:20 INFO - PROCESS | 1655 | |i|pagename|runs| 18:22:20 INFO - PROCESS | 1655 | |0;ai-astar;109;118;118;147;177;119;121;119;119;119 18:22:20 INFO - PROCESS | 1655 | |1;audio-beat-detection;173;152;148;154;117;115;115;118;150;151 18:22:20 INFO - PROCESS | 1655 | |2;audio-dft;198;212;213;225;200;196;198;197;198;196 18:22:20 INFO - PROCESS | 1655 | |3;audio-fft;82;60;60;88;54;77;103;54;65;52 18:22:20 INFO - PROCESS | 1655 | |4;audio-oscillator;91;73;84;75;74;72;83;72;70;85 18:22:20 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;91;89;89;91;90;91;93;90;91 18:22:20 INFO - PROCESS | 1655 | |6;imaging-darkroom;108;155;150;98;97;97;98;98;97;96 18:22:20 INFO - PROCESS | 1655 | |7;imaging-desaturate;93;96;89;93;109;94;93;93;99;122 18:22:20 INFO - PROCESS | 1655 | |8;json-parse-financial;50;55;100;99;93;66;66;64;64;89 18:22:20 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;68;87;72;72;113;105;58;80;104;106 18:22:20 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;93;102;93;80;118;112;109;111;80;93 18:22:20 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;146;156;137;151;134;151;162;158;213;161 18:22:20 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;184;194;179;178;177;180;178;191;175;175 18:22:20 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;69;70;63;93;88;84;69;74;83;90 18:22:20 INFO - PROCESS | 1655 | __end_tp_report 18:22:20 INFO - PROCESS | 1655 | __start_cc_report 18:22:20 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,984 18:22:20 INFO - PROCESS | 1655 | __end_cc_report 18:22:20 INFO - PROCESS | 1655 | __startTimestamp1486693340108__endTimestamp 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | ------- Summary: start ------- 18:22:20 INFO - PROCESS | 1655 | Number of tests: 14 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:126.60 Median:119.00 stddev:20.21 (17.0%) stddev-sans-first:20.41 18:22:20 INFO - PROCESS | 1655 | Values: 109.0 118.0 118.0 147.0 177.0 119.0 121.0 119.0 119.0 119.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:150.50 stddev:21.01 (14.0%) stddev-sans-first:18.41 18:22:20 INFO - PROCESS | 1655 | Values: 173.0 152.0 148.0 154.0 117.0 115.0 115.0 118.0 150.0 151.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:203.30 Median:199.00 stddev:9.90 (5.0%) stddev-sans-first:10.31 18:22:20 INFO - PROCESS | 1655 | Values: 198.0 212.0 213.0 225.0 200.0 196.0 198.0 197.0 198.0 196.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:69.50 Median:62.50 stddev:17.21 (27.5%) stddev-sans-first:17.65 18:22:20 INFO - PROCESS | 1655 | Values: 82.0 60.0 60.0 88.0 54.0 77.0 103.0 54.0 65.0 52.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:79.00 stddev:7.19 (9.1%) stddev-sans-first:5.85 18:22:20 INFO - PROCESS | 1655 | Values: 91.0 73.0 84.0 75.0 74.0 72.0 83.0 72.0 70.0 85.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:91.00 stddev:1.26 (1.4%) stddev-sans-first:1.24 18:22:20 INFO - PROCESS | 1655 | Values: 89.0 91.0 89.0 89.0 91.0 90.0 91.0 93.0 90.0 91.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:97.00 stddev:23.00 (23.7%) stddev-sans-first:24.39 18:22:20 INFO - PROCESS | 1655 | Values: 108.0 155.0 150.0 98.0 97.0 97.0 98.0 98.0 97.0 96.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:93.00 stddev:9.99 (10.7%) stddev-sans-first:10.43 18:22:20 INFO - PROCESS | 1655 | Values: 93.0 96.0 89.0 93.0 109.0 94.0 93.0 93.0 99.0 122.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:74.60 Median:66.00 stddev:18.70 (28.3%) stddev-sans-first:17.59 18:22:20 INFO - PROCESS | 1655 | Values: 50.0 55.0 100.0 99.0 93.0 66.0 66.0 64.0 64.0 89.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:86.50 Median:70.00 stddev:19.29 (27.6%) stddev-sans-first:19.26 18:22:20 INFO - PROCESS | 1655 | Values: 68.0 87.0 72.0 72.0 113.0 105.0 58.0 80.0 104.0 106.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:99.10 Median:80.00 stddev:13.39 (16.7%) stddev-sans-first:14.02 18:22:20 INFO - PROCESS | 1655 | Values: 93.0 102.0 93.0 80.0 118.0 112.0 109.0 111.0 80.0 93.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:157.00 stddev:21.86 (13.9%) stddev-sans-first:22.83 18:22:20 INFO - PROCESS | 1655 | Values: 146.0 156.0 137.0 151.0 134.0 151.0 162.0 158.0 213.0 161.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:179.50 stddev:6.57 (3.7%) stddev-sans-first:6.89 18:22:20 INFO - PROCESS | 1655 | Values: 184.0 194.0 179.0 178.0 177.0 180.0 178.0 191.0 175.0 175.0 18:22:20 INFO - PROCESS | 1655 | 18:22:20 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:83.50 stddev:10.52 (12.6%) stddev-sans-first:10.61 18:22:20 INFO - PROCESS | 1655 | Values: 69.0 70.0 63.0 93.0 88.0 84.0 69.0 74.0 83.0 90.0 18:22:20 INFO - PROCESS | 1655 | -------- Summary: end -------- 18:22:20 INFO - PROCESS | 1655 | 18:22:21 INFO - TEST-INFO | 1655: exit 0 18:22:21 INFO - TEST-OK | kraken | took 61309ms 18:22:21 INFO - SUITE-END | took 1177s 18:22:21 INFO - Completed test suite (00:19:37) 18:22:21 INFO - javascript benchmark 18:22:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7022.112744822108, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.845239939823, 3265.7877237979546, 3281.9999999999995, 3304.947484523585, 3308.718758905493, 5330.3603567571945, 5427.267318911946, 5433.646812957155, 5443.972780136104, 5534.0, 5067.366579177612, 5335.733213339327, 5430.5384042356445, 5454.890902181954, 5527.060399732045, 3716.739828212026, 4950.356453661037, 5104.923426148611, 5153.69077855328, 5159.303494028296, 4924.162892308316, 5127.641065125443, 5171.0, 5176.7929282828645, 5194.766235519411, 4903.509649035103, 4958.727270000149, 5081.669691470042, 5087.720175390352, 5166.07010738067, 4988.326575912241, 5019.121653710586, 5031.572316353115, 5065.822696205615, 5121.615878809067, 4999.250112483121, 5016.573591244749, 5018.247262910556, 5036.471170527078, 5085.1355269604255, 4516.774161291923, 5275.472452754732, 5311.973440132813, 5448.972755136238, 5511.669299841982, 5804.88390232193, 5960.850978725523, 6008.48927841134, 6027.457528822383, 6140.723667434976, 5226.7717086484645, 5936.940630593682, 6125.816225513242, 6161.845953851145, 6163.445289923883, 4956.4547899731, 5360.579446446694, 5623.0721930881355, 5643.040683083886, 5645.15322701594, 5517.641353312022, 5538.698405874617, 6006.339302676702, 6119.969400152971, 6166.475849552839, 5922.3485416604135, 5931.940680593225, 5990.73041713124, 6027.7287522061615, 6045.516358691294, 6123.663198524124, 6138.631682099089, 6141.109539116893, 6144.907826382608, 6154.476869466056, 5267.525922666979, 5769.221155144001, 5793.391693872167, 5959.73181206838, 6002.939970600238, 6072.726727297282, 6075.817725468243, 6093.086037094383, 6104.694765261719, 6126.479249263863, 4836.758162091881, 4873.951260487421, 4888.413390393104, 4892.510748925079, 4932.087563800636, 4304.526502084831, 4308.052228509723, 4325.437693099877, 4343.565643435694, 4379.167958088017, 5624.634398764068, 5632.352279487829, 5643.548516118701, 5659.745311460994, 5692.0, 5729.8567535811935, 6081.604695694854, 6140.416660417253, 6154.969225153845, 6155.261368635793, 5392.191171324254, 5438.15708565173, 5555.666660000413, 5651.208830763697, 5651.802186923438, 5273.182656688219, 5321.521063104339, 5339.252504649352, 5372.892542149213, 5377.650452720561, 6221.720022598994, 6282.874342513124, 6284.685765711604, 6287.937120628827, 6305.274893387319, 5869.7945571904775, 5965.791197308074, 6125.9693701531205, 6165.414285642857, 6168.136460895388, 5167.638265321391, 5322.654027488201, 6200.286966998853, 6273.215848018997, 6291.0, 6007.4893634039845, 6034.487068599043, 6049.243844519435, 6062.4240697135465, 6095.817125486243, 5791.884162316645, 6134.539909506716, 6137.7851775187655, 6153.938460615336, 6164.291106522807, 4954.77703503336, 5307.65500242483, 5847.649141051551, 5907.940920590911, 5994.640321580545, 4837.637177211653, 5847.970760146342, 5913.201717768046, 5921.822345329475, 5924.822255332347, 5210.557102646318, 5567.944320556742, 5609.102543593006, 5773.595848290579, 5783.884322313446, 5455.9454405455435, 5937.851553711192, 5981.461668449861, 5991.550633702403, 6021.969890150521, 4767.237242041257, 5201.895962080813, 5751.971240143773, 5793.565482588907, 5804.9709751452665], "value": 5473.27213083725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.3636572421403, 479.5108988831393, 479.7313504437513, 540.3380858448401, 570.7659859457623, 28775.71224287759, 37511.0, 37590.624093759056, 38918.80540597293, 38955.0, 31811.522827157612, 39994.200115997664, 40107.39838902419, 40197.59802401975, 40244.79877600615, 8538.786530336745, 9027.593758280893, 9066.909330906705, 9083.591238394256, 9163.450192988408, 8876.733697989072, 8988.056254093324, 8994.415363001417, 9005.0, 9021.909780902173, 8794.472331660121, 8954.641814327419, 8967.865482017774, 8985.415947963395, 8996.8650470243, 8876.644934202624, 8894.911050889505, 8951.820963580756, 8960.1935825776, 8985.460872347614, 8812.339074569432, 8844.823103537958, 8914.86627700585, 8915.375923685344, 8921.464712117293, 35410.29179416423, 35708.0, 35719.82140089309, 35759.64240357589, 35761.46357804635, 3764.9811750941344, 3779.074126838942, 3809.847606095753, 3822.694184465236, 3923.88228353147, 26953.0, 27396.45207095867, 27535.0, 27655.861720691468, 27807.721922780715, 3013.909582712522, 3072.201227680797, 3211.0687900508824, 3246.2858171202297, 3249.675032496755, 436389.0, 437920.0, 441386.0, 442082.78958605323, 442607.0, 46960.06079878383, 51028.489715102376, 51453.71365715887, 51600.48399515957, 51656.70858228574, 56046.71976640172, 56285.718571406884, 56325.71837140788, 56475.870482590115, 56546.717266413405, 13540.864591354157, 14009.0, 14012.509562165274, 14017.158970461805, 14024.158550487004, 11019.173561982883, 11149.609763658233, 11204.495797689124, 11216.439178041066, 11217.439128043565, 16460.83539164593, 17179.82820171807, 17191.484255472355, 17239.913800430917, 17243.913780431016, 6865.450763938873, 7064.364207221272, 7756.0, 7784.455088143889, 7811.843763124706, 833.2833762963846, 838.2455789789238, 855.0978717453022, 864.5072308783933, 869.9347548933855, 26128.216153515426, 28187.0, 28214.85892570524, 28969.56545651817, 29820.403591928472, 1004.8492726091145, 1033.7674023344837, 1045.2056437107701, 1058.1217589400778, 1170.3913964738288, 14255.857441425453, 14717.484888028868, 14990.550283491513, 15046.699066018837, 15179.306936130555, 545470.0, 547728.0, 550243.0, 550576.2125461833, 552192.0, 56432.0, 56738.7163064182, 57515.13727294094, 57531.0, 57554.42445575575, 1304.1262354222692, 1349.0691422918155, 1351.9729605408033, 1357.5316515802033, 1373.663452454155, 25019.0, 26718.0, 27147.0, 28157.43685126245, 28250.576241355986, 36528.63471365252, 42863.71408857739, 43237.13525729405, 43339.349909750745, 43472.56527434685, 427719.0, 431690.0, 432150.0, 432310.0, 433956.0, 24179.15372961938, 24609.630855536823, 24647.753522465264, 24659.630105548073, 24678.506429870944, 33721.662783372834, 41128.0, 42408.575914241694, 43056.0, 44384.55615443804, 33918.66081339155, 34022.14944626404, 34842.47736283907, 35093.94718158459, 35477.82261088678, 33962.320753584296, 34006.82996585001, 34848.0, 35341.469877951335, 35574.0, 33142.0, 33258.00225993224, 33491.834606499346, 33562.664373355954, 34696.479552807235, 29298.560521591768, 30926.536101958038, 30952.535711963887, 30998.22504437317, 31092.844535777178, 22487.437814054127, 22700.432489187242, 23383.298501044996, 23755.64366534538, 23934.640980385655], "value": 18277.9227419485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.3494157519855, 381.046330011267, 392.68388946897744, 396.5637798421736, 413.86394347516074, 18216.271349146034, 18792.0, 19333.80666193336, 19378.418647440583, 19551.02244887757, 20211.494712632193, 20551.897240513776, 20684.27605033824, 20751.169953201854, 20931.058102385392, 5432.67403955762, 5454.181872719084, 5578.944210557883, 5645.576581756364, 5662.6036177467595, 5546.500814926656, 5643.266375371196, 5650.378458369576, 5653.830385088454, 5670.4896559309655, 5432.093839770181, 5444.50999410053, 5571.526420254283, 5581.5255703265275, 5592.552595792326, 5332.9733351333, 5521.779128834842, 5523.392426833045, 5580.637258578179, 5593.720313984305, 4844.176489996709, 4928.778204980785, 4930.309756634074, 4937.407511098655, 4957.5042495750495, 18380.059190529584, 19704.70442943357, 23660.526789464286, 24317.513649727083, 24343.634845477332, 2625.2911713837157, 2630.736926307373, 2633.868306584682, 2657.973420265792, 2697.2178068360154, 14870.851291487055, 15386.692266154727, 15472.303746331441, 15558.844411555852, 16027.679446411006, 1799.4601619514224, 1821.5172979160534, 1827.3056238629235, 1828.1133650179786, 1828.6342731453763, 86549.0, 98326.0, 113286.43356783246, 121386.57226855501, 123259.38370308091, 7346.669399877019, 8385.371097167737, 10629.25595208328, 10634.384309334864, 10928.672139835817, 11220.214584979136, 12799.29603871796, 12905.806412903814, 12940.870591294155, 12954.740905181843, 7568.091828980558, 7750.496217745828, 7768.922310776933, 7805.453618246782, 7856.528608283521, 6314.557980941382, 7663.118741344705, 7674.8081297968, 7775.22247775218, 7836.393539324425, 4034.172994536113, 4131.153113611703, 4133.0, 4193.035601811601, 4194.853180138681, 2015.4256037029377, 2058.341330774138, 2070.730804995341, 2071.616750901088, 2076.1591555420077, 786.8780339047457, 1010.7978404319166, 1014.8738393066513, 1035.6012935020071, 1049.490996866519, 19213.711794323095, 20683.896580517, 20706.896465517577, 20735.481612959797, 20808.27171049006, 899.3659470073583, 1029.6910926721953, 1031.3648627756006, 1058.899404556566, 1064.8775390829999, 10515.737106572244, 10843.349399036084, 10861.511231994578, 10892.455377231106, 10912.0, 409209.0, 490360.0, 570331.0, 658457.0, 659528.0, 131369.0, 140976.0, 146745.26627366795, 148823.25588372204, 155498.0, 1001.47923079998, 1017.3387298256044, 1043.2488608202082, 1050.3750268590177, 1055.2982266792496, 14973.475928342456, 17944.730829037835, 18010.72983905269, 19415.126319315947, 19552.511187220432, 44468.55531444644, 46588.83527911694, 52219.47780522298, 54219.72890135524, 54600.18099728587, 64393.746218882115, 67082.0, 73178.0, 76323.03900246466, 81634.59182704048, 5813.418658134153, 6915.550489218184, 8186.222308880647, 8312.750617481484, 8368.707095251637, 7459.067616547932, 9580.95209523948, 11990.0, 12152.93923530412, 12857.807132893202, 11289.830652540055, 11328.093752499944, 11487.655370338904, 11512.424378781196, 11748.883856033663, 9251.259899207913, 10112.24158188124, 10262.846057309296, 11165.050970667584, 11490.0, 10663.840042399215, 10878.619248326271, 11212.103031757277, 11554.248973816675, 11691.356975366436, 5640.830775076754, 7430.851382972202, 7746.92253077462, 7846.5292082475235, 8582.742517724479, 9857.0, 10963.890361096286, 11101.888981110085, 11420.0, 11517.53929842797], "value": 9331.649154628205, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1207.516993202719, 1223.4584423626231, 1388.1670997401566, 1398.2589227709318, 1412.7174565086984, 8036.075851277104, 8252.628631711572, 8393.328533717304, 8555.657773689061, 8568.400211985163, 8419.452735572198, 8571.571421428935, 8579.742607721762, 8587.0, 8601.612927418266, 2258.3337915315024, 2285.817134629234, 2295.093438091961, 2303.665968434576, 2315.0045480443405, 2249.8425110242288, 2315.976840231593, 2316.270374831931, 2325.5116425550655, 2329.5457385809773, 2261.6720575516542, 2269.8751568663715, 2282.9086836526517, 2283.4405570635136, 2294.678744975705, 2215.235743668432, 2249.606318894187, 2263.852849564781, 2288.1533832482087, 2291.049214575949, 1981.4352909420745, 2061.9587608247753, 2225.4658881868395, 2268.160780511205, 2283.8515496492673, 7043.788686339418, 8169.8970638964165, 8350.665973361058, 8362.581870906492, 8401.949756280464, 5041.991601679679, 5160.716160611165, 5179.430262671103, 5193.584513238932, 5206.947930520684, 3279.6064472263242, 3308.470644696837, 3375.1899544109483, 3377.3751855906735, 3382.780119292238, 975.5024937281967, 1184.3012622552649, 1231.7536492701495, 1242.2608547913976, 1675.4889758623615, 10574.889636588125, 10585.73535661607, 10872.347659140476, 10901.890981090168, 10925.180611454094, 4249.660027197817, 4466.196084704784, 4480.305552639281, 4510.75190864499, 4548.886277843014, 7371.226021267797, 7409.518381305198, 8790.077041910636, 8816.250618697482, 8862.64549418029, 4237.851675191354, 5113.411957624847, 5113.795448182105, 5130.820421285237, 5139.7687104080405, 4572.3827283317, 4643.72137671734, 4676.438827340741, 4708.599769019672, 4725.243960966229, 3630.8003059831435, 3661.139632186434, 3684.8526058957877, 3725.2363265530503, 3726.6832319252626, 1169.4094482286391, 1169.6432588060723, 1172.3083380805365, 1174.395186478964, 1184.283508477376, 713.6859781696037, 718.7448455798221, 738.1437532462318, 744.5421066044344, 763.023330137425, 5337.6797392156595, 5519.586031047687, 5629.268195134607, 5657.915131273035, 5709.486146246858, 544.1891581543472, 547.8411260734422, 569.6126633888995, 575.7092668202531, 577.2207519848172, 3682.208325210093, 3727.471736587986, 3769.509963704701, 3872.283627528916, 3903.6291552302487, 9619.759506012266, 9909.256805739598, 9966.2525310602, 9967.601295948225, 9981.151602113756, 9814.0, 9858.704238872846, 9906.900930990598, 9920.751981200528, 9926.602935882484, 7578.924210757932, 7595.392368610498, 7764.534127952341, 7838.294553490327, 7848.686052557948, 4503.176213633484, 4573.5426457354, 5512.724363781715, 5619.353774316006, 5650.858728531655, 8822.426542274732, 9019.503927284062, 9091.863622045543, 9205.907940920504, 9240.122188392092, 9009.459432434074, 9164.358494905558, 9304.674336398193, 9309.162175404246, 9312.627494900398, 6966.303369663196, 7083.82290442743, 7207.639618019183, 7217.566945983258, 7234.421246300178, 7927.009123859518, 8059.597020148852, 8087.353011759163, 8094.0, 8095.676172953016, 7151.141862976476, 7254.056972593529, 7275.454340924558, 7298.854022919618, 7370.152432470334, 5973.372795856372, 6217.253929528486, 6858.491131950963, 7128.501004929605, 7135.714571417086, 5601.075822489406, 6316.273628532777, 6375.330590287953, 6430.903536446864, 6434.163558737334, 4916.827911023097, 5004.649674522749, 5019.0, 5101.132807422821, 5107.87230319245, 4943.0, 4983.501649834988, 5078.441371449211, 5174.482551744795, 5181.637285390138], "value": 4269.198181117751, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.2203409277984, 352.64030688697545, 358.4014695458584, 365.4262807392392, 365.7604269203672, 4197.321071571373, 5173.490856934393, 5315.920261196085, 5492.5605951523885, 5503.679117011919, 4418.801153948072, 4695.718256904588, 5253.212018197272, 5426.2674538937235, 5428.669975855921, 4262.3393374027, 4290.721103128302, 4294.119705460389, 4296.291111966521, 4319.935200971988, 3682.4108142697173, 3978.661813745835, 4048.9797551012352, 4137.8758637240935, 4318.956810431887, 3661.2311414602964, 4203.264428724976, 4204.5375008749015, 4224.387463817745, 4236.639885609711, 4233.407322974786, 4233.894152646193, 4244.766537840417, 4260.659147268211, 4285.5928686774705, 3548.1484443733316, 3743.0, 3771.585125636178, 3779.0, 3788.507494025759, 4748.881277968044, 5044.268581055763, 5180.766865491062, 5260.947390526084, 5272.1300985337375, 4279.994201362677, 4897.706137631718, 4918.704877707349, 4924.301116114934, 4943.431505376856, 5424.911409107522, 5772.826815195551, 5790.901867500909, 5793.4785869271545, 5798.681072541009, 3543.0, 3934.9803250983564, 4096.40602112692, 4132.235536425741, 4148.294789885696, 4797.040591881638, 5681.091025435951, 5813.273340832438, 6022.163240211734, 6077.24034495688, 3497.1257185703575, 4163.666906647461, 4225.302825033835, 4226.971053894148, 4229.788510574459, 3824.4072168813877, 4243.342281946303, 4278.55075217104, 4279.807408666617, 4304.870853874389, 4060.3914556978043, 4471.731696098244, 4922.581580565692, 4931.753412329441, 4933.629977751683, 5747.540196784247, 5818.418158184148, 5821.679807610537, 5828.522877776928, 5855.00464920956, 1237.8920865825037, 1267.6957530192872, 1408.9855304181178, 1410.971780564383, 1412.5479846449039, 832.937529685276, 969.4231932000448, 977.7213494154274, 987.693814917378, 988.125508924607, 4720.787564559603, 5158.561522270649, 5212.582993360522, 5214.757654208726, 5222.556082732935, 5010.094145395662, 5242.7378631068295, 5374.785008599691, 5661.772789510022, 5768.9711551442815, 4340.784580317453, 5019.899602007939, 5212.722882893715, 5224.346956630421, 5224.891189742098, 2605.4919290738503, 2635.314818147257, 2859.8856045758353, 2989.40211957605, 3019.8037127586636, 4916.475892473345, 5712.886232094152, 5910.468057874726, 5948.46066590378, 5948.95893218685, 4801.159797035505, 5286.3392075990505, 5750.971245143747, 5958.404159584007, 6150.323464418911, 5221.903400285983, 5403.972980135074, 5627.971860140673, 6139.47814435769, 6180.47465965398, 5161.7677204525135, 5203.947960520346, 5560.416156303691, 5942.61373010753, 6042.395760423922, 5505.25642563061, 5888.852778680567, 5893.381194974466, 5924.318703348998, 5936.940630593812, 5370.516653501204, 5374.570034397317, 5608.261438953938, 5986.132010858314, 6172.506199503939, 3432.987268755673, 3683.3001729671278, 3721.5534135903863, 3931.4692516509904, 3985.701072419638, 5244.632875698664, 5402.540784033401, 5460.907818436249, 5938.792142275, 5950.226470558973, 4983.426905905722, 5123.0020292086865, 5442.6462279951675, 5561.944380556304, 5750.281214848144, 5024.899502010013, 5425.430329815313, 5687.146927960839, 5725.652482482329, 5769.951667158099, 4995.300657907823, 5119.668886089569, 5630.5214056805635, 5662.489256660732, 5663.32040155184, 4830.975845120752, 5470.292205358373, 5541.473560011793, 5585.357683866245, 5773.278340207474, 5201.687898726089, 5594.915711530524, 5607.186957891165, 5637.449701332166, 5649.802256920988], "value": 4194.575679568404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.8105289992353, 665.6838001949071, 677.7872882536777, 694.3785312145629, 765.7970637780985, 14051.789223161648, 14633.63415914604, 15030.02304850184, 15132.84867151331, 15245.161516116608, 13603.38784754686, 13756.24875024994, 13900.513482028131, 13954.372053257603, 13987.0, 847.5423271433434, 859.1751918158544, 861.6381119929645, 861.7974775927652, 863.3093525179862, 840.3361344537808, 860.4106187261715, 870.0212261206143, 877.0440220160025, 879.0989236033085, 899.4108858697564, 909.9590518426687, 910.3672947301628, 912.2428384440898, 914.5381582300929, 896.7175339767953, 902.5261737587754, 909.3725329522641, 910.6311943662827, 913.4336711239041, 661.0579923608839, 675.2909445082693, 675.7668604331498, 676.8206425297251, 677.4478799778215, 14177.43290268388, 14204.928975355057, 14947.40210391583, 15103.244837758068, 15143.772843407247, 13645.590632281048, 13769.862301376957, 13945.790813137812, 13952.302384880817, 13985.3706583204, 11350.262232954832, 11501.798247145523, 11645.70885727855, 11990.0, 12133.575324863587, 3443.328550932569, 3486.5467489226494, 3496.5104885315823, 3499.6325385834375, 3620.348337299285, 102149.489252554, 102446.0, 102921.0, 103171.4841425788, 104186.0, 10440.321379110486, 10463.215258855464, 10669.253152279422, 10682.0, 10766.24636275469, 21433.678494822278, 21943.780562194494, 21962.231321903655, 22044.33866983993, 22660.320190394315, 7367.8894816577795, 7475.102987641518, 7528.134264559646, 7542.924570754332, 7800.648970796215, 4092.4475195848777, 4167.1457351242925, 4203.432536607519, 4203.789810509462, 4204.0, 3454.4127498325347, 3698.4452332150395, 3700.833462494194, 3725.720570957189, 3739.8691045813266, 677.5392732941647, 678.7658257901016, 679.9456043516606, 688.6797639097844, 693.9375456208966, 587.224863180606, 636.4144986612328, 637.0666972884653, 697.1808125452573, 699.2902204262678, 9596.95201523988, 9928.751781205528, 9933.205343572497, 9965.750856228507, 10144.746381340377, 543.6466296906963, 584.5294537896949, 588.7821506042792, 616.7255571270831, 644.4489961083207, 4095.3037983542868, 4540.205464043779, 4630.236011058205, 4645.233536466445, 4816.8795780105775, 68143.65928170328, 79216.0, 80124.0, 80994.5950270245, 88127.1187288119, 38529.84410467691, 65583.34416655867, 68350.0, 68848.6557567219, 74440.25559744441, 56765.71617141971, 57923.0, 66223.0, 68783.0, 70767.0, 8608.09614990417, 10014.017048296753, 10085.949570252102, 10562.690492882562, 10687.732806679895, 33387.16532086717, 42913.57086429096, 43977.56022439735, 44492.55507445014, 44540.77729611331, 46562.76718616385, 61588.76822463615, 62084.0, 62758.372416275255, 63459.730805384555, 12129.029677625946, 12335.876641233473, 12849.421776019915, 13003.609891703263, 13086.803697944348, 13773.755117821276, 14294.857051429768, 14524.49164279245, 14740.484083057041, 14780.704385912437, 13133.671658208621, 13480.325983700972, 13598.93200533991, 13842.930785346009, 13984.021118521607, 13312.267825269702, 13528.255945922676, 13660.043796934517, 13859.79210311826, 14020.859791401956, 13063.542776002794, 13275.0, 13396.464141434233, 13661.726765464833, 13866.584002479942, 10601.840972385575, 10735.0, 10864.168106615709, 10881.673549793519, 11037.613683521038, 8887.677208287792, 9253.629854806002, 10022.047905448972, 10256.38461692301, 10257.974202579682], "value": 7171.265077927127, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 118.0, 147.0, 177.0, 119.0, 121.0, 119.0, 119.0, 119.0], "value": 126.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 152.0, 148.0, 154.0, 117.0, 115.0, 115.0, 118.0, 150.0, 151.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 212.0, 213.0, 225.0, 200.0, 196.0, 198.0, 197.0, 198.0, 196.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 88.0, 54.0, 77.0, 103.0, 54.0, 65.0, 52.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 84.0, 75.0, 74.0, 72.0, 83.0, 72.0, 70.0, 85.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 89.0, 91.0, 90.0, 91.0, 93.0, 90.0, 91.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 155.0, 150.0, 98.0, 97.0, 97.0, 98.0, 98.0, 97.0, 96.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 89.0, 93.0, 109.0, 94.0, 93.0, 93.0, 99.0, 122.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 55.0, 100.0, 99.0, 93.0, 66.0, 66.0, 64.0, 64.0, 89.0], "value": 74.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 87.0, 72.0, 72.0, 113.0, 105.0, 58.0, 80.0, 104.0, 106.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 102.0, 93.0, 80.0, 118.0, 112.0, 109.0, 111.0, 80.0, 93.0], "value": 99.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 156.0, 137.0, 151.0, 134.0, 151.0, 162.0, 158.0, 213.0, 161.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 194.0, 179.0, 178.0, 177.0, 180.0, 178.0, 191.0, 175.0, 175.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 63.0, 93.0, 88.0, 84.0, 69.0, 74.0, 83.0, 90.0], "value": 78.3, "unit": "score"}]}]} 18:22:21 INFO - Return code: 0 18:22:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:21 ERROR - # TBPL SUCCESS # 18:22:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:21 INFO - 2017-02-09 18:22:21.416 screenresolution[1660:10644] starting screenresolution argv=screenresolution get 18:22:21 INFO - 2017-02-09 18:22:21.419 screenresolution[1660:10644] Display 0: 1600x1200x32@60 18:22:21 INFO - 2017-02-09 18:22:21.425 screenresolution[1661:10647] starting screenresolution argv=screenresolution list 18:22:21 INFO - Available Modes on Display 0 18:22:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:21 INFO - Intel Iris: 18:22:21 INFO - Chipset Model: Intel Iris 18:22:21 INFO - Type: GPU 18:22:21 INFO - Bus: Built-In 18:22:21 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:21 INFO - Vendor: Intel (0x8086) 18:22:21 INFO - Device ID: 0x0a2e 18:22:21 INFO - Revision ID: 0x0009 18:22:21 INFO - Displays: 18:22:21 INFO - Display: 18:22:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:21 INFO - Display Serial Number: 1600x1200 60 18:22:21 INFO - Main Display: Yes 18:22:21 INFO - Mirror: Off 18:22:21 INFO - Online: Yes 18:22:21 INFO - Rotation: Supported 18:22:21 INFO - Return code: 0 18:22:21 INFO - Running post-action listener: _resource_record_post_action 18:22:21 INFO - [mozharness: 2017-02-10 02:22:21.666618Z] Finished run-tests step (success) 18:22:21 INFO - Running post-run listener: _resource_record_post_run 18:22:21 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 38853120; Write bytes: 557186560; Read time: 249; Write time: 1715 18:22:21 INFO - TinderboxPrint: CPU usage
54.2% 18:22:21 INFO - TinderboxPrint: I/O read bytes / time
38,853,120 / 249 18:22:21 INFO - TinderboxPrint: I/O write bytes / time
557,186,560 / 1,715 18:22:21 INFO - TinderboxPrint: CPU idle
2,197.1 (45.8%) 18:22:21 INFO - TinderboxPrint: CPU system
961.3 (20.0%) 18:22:21 INFO - TinderboxPrint: CPU user
1,643.2 (34.2%) 18:22:21 INFO - TinderboxPrint: Swap in / out
291,332,096 / 0 18:22:21 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250774016; Write bytes: 243142656; Read time: 18154; Write time: 525 18:22:21 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35256832; Write bytes: 301492224; Read time: 225; Write time: 1151 18:22:22 INFO - Running post-run listener: _upload_blobber_files 18:22:22 INFO - Blob upload gear active. 18:22:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:22 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:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:22 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:22:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:23 INFO - (blobuploader) - INFO - Done attempting. 18:22:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:22:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:22:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:24 INFO - (blobuploader) - INFO - Done attempting. 18:22:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:25 INFO - (blobuploader) - INFO - Done attempting. 18:22:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:28 INFO - (blobuploader) - INFO - Done attempting. 18:22:28 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:28 INFO - Return code: 0 18:22:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72bbd3af345e2458b8f7c29bd4de9dcb1d6fb87ef3072d3e0392d4d0c5362e7c1d20344e2eb8028a335cf3174753044d09c6a26f046d190dfbd5392ca4ca9a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea3bb5193bed905e2f7e7adbf5dc7bf39fb7fcc26a7150b640ef0c9ff03f5084f676f2727cc490a2f91efdaac8d7804c07b387899eef1646df410dc24984de74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eaeccd8f5312869a084909d0b1a138742170c79c9b83b01c0102a33025f08e5531b096aa6b9f346913144c6ed577cdd3c1767e330c8e62c90e52bd26c3e9ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f74fb7d3588ccf0f14af48a72ddc002ccea1cad423531d4a50e916f6c155410091c801ce4bb34a20d569b5f158c1b94445e4f5b40963433094199eac344d520c"} 18:22:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:28 INFO - Contents: 18:22:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72bbd3af345e2458b8f7c29bd4de9dcb1d6fb87ef3072d3e0392d4d0c5362e7c1d20344e2eb8028a335cf3174753044d09c6a26f046d190dfbd5392ca4ca9a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea3bb5193bed905e2f7e7adbf5dc7bf39fb7fcc26a7150b640ef0c9ff03f5084f676f2727cc490a2f91efdaac8d7804c07b387899eef1646df410dc24984de74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eaeccd8f5312869a084909d0b1a138742170c79c9b83b01c0102a33025f08e5531b096aa6b9f346913144c6ed577cdd3c1767e330c8e62c90e52bd26c3e9ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f74fb7d3588ccf0f14af48a72ddc002ccea1cad423531d4a50e916f6c155410091c801ce4bb34a20d569b5f158c1b94445e4f5b40963433094199eac344d520c"} 18:22:28 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:28 INFO - Copying logs to upload dir... 18:22:28 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.587197 ========= master_lag: 0.43 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-09 18:22:28.549359) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 18:22:28.553314) ========= 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.4GZtLzjwcy/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.lONOzqYzPG/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72bbd3af345e2458b8f7c29bd4de9dcb1d6fb87ef3072d3e0392d4d0c5362e7c1d20344e2eb8028a335cf3174753044d09c6a26f046d190dfbd5392ca4ca9a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea3bb5193bed905e2f7e7adbf5dc7bf39fb7fcc26a7150b640ef0c9ff03f5084f676f2727cc490a2f91efdaac8d7804c07b387899eef1646df410dc24984de74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eaeccd8f5312869a084909d0b1a138742170c79c9b83b01c0102a33025f08e5531b096aa6b9f346913144c6ed577cdd3c1767e330c8e62c90e52bd26c3e9ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f74fb7d3588ccf0f14af48a72ddc002ccea1cad423531d4a50e916f6c155410091c801ce4bb34a20d569b5f158c1b94445e4f5b40963433094199eac344d520c"} build_url:https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/SXvgBXmhR5u0epHFB3AAIw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a72bbd3af345e2458b8f7c29bd4de9dcb1d6fb87ef3072d3e0392d4d0c5362e7c1d20344e2eb8028a335cf3174753044d09c6a26f046d190dfbd5392ca4ca9a7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea3bb5193bed905e2f7e7adbf5dc7bf39fb7fcc26a7150b640ef0c9ff03f5084f676f2727cc490a2f91efdaac8d7804c07b387899eef1646df410dc24984de74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1eaeccd8f5312869a084909d0b1a138742170c79c9b83b01c0102a33025f08e5531b096aa6b9f346913144c6ed577cdd3c1767e330c8e62c90e52bd26c3e9ca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f74fb7d3588ccf0f14af48a72ddc002ccea1cad423531d4a50e916f6c155410091c801ce4bb34a20d569b5f158c1b94445e4f5b40963433094199eac344d520c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 18:22:28.588302) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:22:28.588621) ========= 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.4GZtLzjwcy/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.lONOzqYzPG/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.004944 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 18:22:28.637302) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 18:22:28.637583) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 18:22:28.637867) ========= ========= Total master_lag: 0.57 =========