builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0296 starttime: 1488274624.13 results: success (0) buildid: 20170228005036 builduid: 80b63a5c1c174256956842c60ba46e30 revision: b53997386a332c2d1f36ce2aee1feccd8ba6fc56 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.128896) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.129305) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.130054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.221198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.221469) ========= 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.brZ0uDrkLj/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.V7INf5iTVz/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-28 01:37:04-- 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% 19.1M=0.001s 2017-02-28 01:37:04 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174944 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.420060) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.420381) ========= 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.brZ0uDrkLj/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.V7INf5iTVz/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.029077 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:04.497792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-28 01:37:04.498151) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b53997386a332c2d1f36ce2aee1feccd8ba6fc56 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b53997386a332c2d1f36ce2aee1feccd8ba6fc56 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.brZ0uDrkLj/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.V7INf5iTVz/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-28 01:37:04,685 truncating revision to first 12 chars 2017-02-28 01:37:04,686 Setting DEBUG logging. 2017-02-28 01:37:04,686 attempt 1/10 2017-02-28 01:37:04,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b53997386a33?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-28 01:37:05,929 unpacking tar archive at: mozilla-aurora-b53997386a33/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520966 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-28 01:37:06.041053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:06.041345) ========= script_repo_revision: b53997386a332c2d1f36ce2aee1feccd8ba6fc56 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:06.041741) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:06.042006) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-28 01:37:06.061752) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-28 01:37:06.062015) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.brZ0uDrkLj/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.V7INf5iTVz/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:06 INFO - MultiFileLogger online at 20170228 01:37:06 in /builds/slave/test 01:37:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:37:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:37:06 INFO - {'append_to_log': False, 01:37:06 INFO - 'base_work_dir': '/builds/slave/test', 01:37:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:37:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:37:06 INFO - 'branch': 'Mozilla-Aurora', 01:37:06 INFO - 'buildbot_json_path': 'buildprops.json', 01:37:06 INFO - 'config_files': ('talos/mac_config.py',), 01:37:06 INFO - 'default_actions': ('clobber', 01:37:06 INFO - 'read-buildbot-config', 01:37:06 INFO - 'download-and-extract', 01:37:06 INFO - 'populate-webroot', 01:37:06 INFO - 'create-virtualenv', 01:37:06 INFO - 'install', 01:37:06 INFO - 'run-tests'), 01:37:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:37:06 INFO - 'download_minidump_stackwalk': True, 01:37:06 INFO - 'download_symbols': 'ondemand', 01:37:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:37:06 INFO - 'tooltool.py': '/tools/tooltool.py', 01:37:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:37:06 INFO - '/tools/misc-python/virtualenv.py')}, 01:37:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:37:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:37:06 INFO - 'installer_path': 'installer.exe', 01:37:06 INFO - 'log_level': 'info', 01:37:06 INFO - 'log_name': 'talos', 01:37:06 INFO - 'log_to_console': True, 01:37:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:37:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:37:06 INFO - 'opt_config_files': (), 01:37:06 INFO - 'pip_index': False, 01:37:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:06 INFO - 'cmd': ('bash', 01:37:06 INFO - '-c', 01:37:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:06 INFO - 'enabled': True, 01:37:06 INFO - 'halt_on_failure': False, 01:37:06 INFO - 'name': 'check_screen_resolution'},), 01:37:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:06 INFO - 'cmd': ('bash', 01:37:06 INFO - '-c', 01:37:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:06 INFO - 'enabled': True, 01:37:06 INFO - 'halt_on_failure': False, 01:37:06 INFO - 'name': 'check_screen_resolution'},), 01:37:06 INFO - 'run_cmd_checks_enabled': True, 01:37:06 INFO - 'sps_profile': False, 01:37:06 INFO - 'sps_profile_interval': 0, 01:37:06 INFO - 'suite': 'dromaeojs-e10s', 01:37:06 INFO - 'system_bits': '32', 01:37:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:37:06 INFO - 'title': 't-yosemite-r7-0296', 01:37:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:37:06 INFO - 'use_talos_json': True, 01:37:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:37:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:37:06 INFO - 'work_dir': 'build'} 01:37:06 INFO - [mozharness: 2017-02-28 09:37:06.350494Z] Running clobber step. 01:37:06 INFO - Running pre-action listener: _resource_record_pre_action 01:37:06 INFO - Running main action method: clobber 01:37:06 INFO - rmtree: /builds/slave/test/build 01:37:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:37:08 INFO - Running post-action listener: _resource_record_post_action 01:37:08 INFO - [mozharness: 2017-02-28 09:37:08.869006Z] Finished clobber step (success) 01:37:08 INFO - [mozharness: 2017-02-28 09:37:08.869135Z] Running read-buildbot-config step. 01:37:08 INFO - Running pre-action listener: _resource_record_pre_action 01:37:08 INFO - Running main action method: read_buildbot_config 01:37:08 INFO - Using buildbot properties: 01:37:08 INFO - { 01:37:08 INFO - "project": "", 01:37:08 INFO - "product": "firefox", 01:37:08 INFO - "script_repo_revision": "production", 01:37:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 01:37:08 INFO - "repository": "", 01:37:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 01:37:08 INFO - "buildid": "20170228005036", 01:37:08 INFO - "pgo_build": "False", 01:37:08 INFO - "basedir": "/builds/slave/test", 01:37:08 INFO - "buildnumber": 14, 01:37:08 INFO - "slavename": "t-yosemite-r7-0296", 01:37:08 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:37:08 INFO - "platform": "yosemite_r7", 01:37:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:37:08 INFO - "branch": "mozilla-aurora", 01:37:08 INFO - "repo_path": "releases/mozilla-aurora", 01:37:08 INFO - "revision": "b53997386a332c2d1f36ce2aee1feccd8ba6fc56", 01:37:08 INFO - "stage_platform": "macosx64", 01:37:08 INFO - "builduid": "80b63a5c1c174256956842c60ba46e30", 01:37:08 INFO - "slavebuilddir": "test" 01:37:08 INFO - } 01:37:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 01:37:08 INFO - Running post-action listener: _resource_record_post_action 01:37:08 INFO - [mozharness: 2017-02-28 09:37:08.872252Z] Finished read-buildbot-config step (success) 01:37:08 INFO - [mozharness: 2017-02-28 09:37:08.872396Z] Running download-and-extract step. 01:37:08 INFO - Running pre-action listener: _resource_record_pre_action 01:37:08 INFO - Running main action method: download_and_extract 01:37:08 INFO - mkdir: /builds/slave/test/build/tests 01:37:08 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')]} 01:37:08 INFO - trying https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 01:37:08 INFO - Downloading https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:37:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 01:37:10 INFO - Downloaded 1199 bytes. 01:37:10 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:37:10 INFO - Using the following test package requirements: 01:37:10 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:37:10 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 01:37:10 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 01:37:10 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'jsshell-mac.zip'], 01:37:10 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 01:37:10 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:37:10 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 01:37:10 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 01:37:10 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 01:37:10 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:37:10 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 01:37:10 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:37:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:37:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:37:10 INFO - Fetch https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:37:11 INFO - Expected file size: 16357693 01:37:11 INFO - Obtained file size: 16357693 01:37:17 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:37:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:37:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:37:17 INFO - Fetch https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:37:18 INFO - Expected file size: 16357693 01:37:18 INFO - Obtained file size: 16357693 01:37:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 01:37:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:37:25 INFO - Fetch https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 01:37:25 INFO - Expected file size: 14403472 01:37:25 INFO - Obtained file size: 14403472 01:37: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')]} 01:37:26 INFO - trying https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:37:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 01:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 01:37:28 INFO - Downloaded 73921546 bytes. 01:37:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:37:28 INFO - mkdir: /builds/slave/test/properties 01:37:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:37:28 INFO - Writing to file /builds/slave/test/properties/build_url 01:37:28 INFO - Contents: 01:37:28 INFO - build_url:https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 01:37:28 INFO - Running post-action listener: _resource_record_post_action 01:37:28 INFO - Running post-action listener: set_extra_try_arguments 01:37:28 INFO - [mozharness: 2017-02-28 09:37:28.811682Z] Finished download-and-extract step (success) 01:37:28 INFO - [mozharness: 2017-02-28 09:37:28.811829Z] Running populate-webroot step. 01:37:28 INFO - Running pre-action listener: _resource_record_pre_action 01:37:28 INFO - Running main action method: populate_webroot 01:37:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:37:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:37:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:37:28 INFO - '--tppagecycles', 01:37:28 INFO - '7'], 01:37:28 INFO - 'tests': ['tsvgm']}}, 01:37:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:37:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:37:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:37:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:37:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['damp', 'tps']}, 01:37:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'tests': ['damp', 'tps']}, 01:37:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['dromaeo_dom']}, 01:37:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:37:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['basic_compositor_video']}, 01:37:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:37:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['a11yr', 01:37:28 INFO - 'ts_paint', 01:37:28 INFO - 'tpaint', 01:37:28 INFO - 'sessionrestore', 01:37:28 INFO - 'sessionrestore_no_auto_restore', 01:37:28 INFO - 'tabpaint']}, 01:37:28 INFO - 'other-e10s': {'tests': ['a11yr', 01:37:28 INFO - 'ts_paint', 01:37:28 INFO - 'tpaint', 01:37:28 INFO - 'sessionrestore', 01:37:28 INFO - 'sessionrestore_no_auto_restore', 01:37:28 INFO - 'tabpaint']}, 01:37:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:37:28 INFO - 'ts_paint', 01:37:28 INFO - 'tpaint', 01:37:28 INFO - 'sessionrestore', 01:37:28 INFO - 'sessionrestore_no_auto_restore', 01:37:28 INFO - 'tabpaint']}, 01:37:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:37:28 INFO - 'ts_paint', 01:37:28 INFO - 'tpaint', 01:37:28 INFO - 'sessionrestore', 01:37:28 INFO - 'sessionrestore_no_auto_restore', 01:37:28 INFO - 'tabpaint']}, 01:37:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['a11yr', 01:37:28 INFO - 'ts_paint', 01:37:28 INFO - 'tpaint', 01:37:28 INFO - 'sessionrestore', 01:37:28 INFO - 'sessionrestore_no_auto_restore', 01:37:28 INFO - 'tabpaint']}, 01:37:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['a11yr', 01:37:28 INFO - 'ts_paint', 01:37:28 INFO - 'tpaint', 01:37:28 INFO - 'sessionrestore', 01:37:28 INFO - 'sessionrestore_no_auto_restore', 01:37:28 INFO - 'tabpaint']}, 01:37:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['tsvgx', 01:37:28 INFO - 'tsvgr_opacity', 01:37:28 INFO - 'tart', 01:37:28 INFO - 'tscrollx', 01:37:28 INFO - 'cart', 01:37:28 INFO - 'tsvg_static']}, 01:37:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:37:28 INFO - 'tsvgr_opacity', 01:37:28 INFO - 'tart', 01:37:28 INFO - 'tscrollx', 01:37:28 INFO - 'cart', 01:37:28 INFO - 'tsvg_static']}, 01:37:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'talos_options': ['--disable-e10s'], 01:37:28 INFO - 'tests': ['tp5o']}, 01:37:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:37:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'talos_options': ['--disable-e10s', 01:37:28 INFO - '--xperf_path', 01:37:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:28 INFO - 'tests': ['tp5n']}, 01:37:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:37:28 INFO - 'talos_options': ['--xperf_path', 01:37:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:28 INFO - 'tests': ['tp5n']}}, 01:37:28 INFO - 'talos.zip': {'path': '', 01:37:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:37:28 INFO - Running post-action listener: _resource_record_post_action 01:37:28 INFO - [mozharness: 2017-02-28 09:37:28.830170Z] Finished populate-webroot step (success) 01:37:28 INFO - [mozharness: 2017-02-28 09:37:28.830296Z] Running create-virtualenv step. 01:37:28 INFO - Running pre-action listener: _resource_record_pre_action 01:37:28 INFO - Running main action method: create_virtualenv 01:37:28 INFO - Creating virtualenv /builds/slave/test/build/venv 01:37:28 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 01:37:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:37:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:37:28 INFO - Using real prefix '/tools/python27' 01:37:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:37:29 INFO - Installing distribute.............................................................................................................................................................................................done. 01:37:32 INFO - Installing pip.................done. 01:37:32 INFO - Return code: 0 01:37:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:37:32 INFO - Reading from file tmpfile_stdout 01:37:32 INFO - Output received: 01:37:32 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) 01:37:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:37:32 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')]} 01:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:32 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')]} 01:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:32 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:32 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 01:37:32 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 01:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:32 INFO - 'HOME': '/Users/cltbld', 01:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:32 INFO - 'LOGNAME': 'cltbld', 01:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:32 INFO - 'MOZ_NO_REMOTE': '1', 01:37:32 INFO - 'NO_EM_RESTART': '1', 01:37:32 INFO - 'PAGER': '/bin/cat', 01:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:32 INFO - 'PWD': '/builds/slave/test', 01:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:32 INFO - 'SHELL': '/bin/bash', 01:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:32 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:32 INFO - 'USER': 'cltbld', 01:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:32 INFO - 'XPC_FLAGS': '0x0', 01:37:32 INFO - 'XPC_SERVICE_NAME': '0', 01:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:33 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 01:37:33 INFO - Cleaning up... 01:37:33 INFO - Return code: 0 01:37:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:37:33 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')]} 01:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:33 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')]} 01:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:33 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:33 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 01:37:33 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 01:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:33 INFO - 'HOME': '/Users/cltbld', 01:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:33 INFO - 'LOGNAME': 'cltbld', 01:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:33 INFO - 'MOZ_NO_REMOTE': '1', 01:37:33 INFO - 'NO_EM_RESTART': '1', 01:37:33 INFO - 'PAGER': '/bin/cat', 01:37:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:33 INFO - 'PWD': '/builds/slave/test', 01:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:33 INFO - 'SHELL': '/bin/bash', 01:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:33 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:33 INFO - 'USER': 'cltbld', 01:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:33 INFO - 'XPC_FLAGS': '0x0', 01:37:33 INFO - 'XPC_SERVICE_NAME': '0', 01:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:33 INFO - Downloading/unpacking psutil>=3.1.1 01:37:33 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 01:37:33 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 01:37:33 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 01:37:33 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 01:37:33 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 01:37:33 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 01:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:37:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:37 INFO - Installing collected packages: psutil 01:37:37 INFO - Running setup.py install for psutil 01:37:37 INFO - building 'psutil._psutil_osx' extension 01:37:37 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 01:37:37 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 01:37:37 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 01:37:37 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 01:37:38 INFO - building 'psutil._psutil_posix' extension 01:37:38 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 01:37:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:37:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:37:38 INFO - ^ 01:37:38 INFO - 1 warning generated. 01:37:38 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 01:37:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:38 INFO - Successfully installed psutil 01:37:38 INFO - Cleaning up... 01:37:38 INFO - Return code: 0 01:37:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:37:38 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')]} 01:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:38 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')]} 01:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:38 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:38 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 01:37:38 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 01:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:38 INFO - 'HOME': '/Users/cltbld', 01:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:38 INFO - 'LOGNAME': 'cltbld', 01:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:38 INFO - 'MOZ_NO_REMOTE': '1', 01:37:38 INFO - 'NO_EM_RESTART': '1', 01:37:38 INFO - 'PAGER': '/bin/cat', 01:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:38 INFO - 'PWD': '/builds/slave/test', 01:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:38 INFO - 'SHELL': '/bin/bash', 01:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:38 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:38 INFO - 'USER': 'cltbld', 01:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:38 INFO - 'XPC_FLAGS': '0x0', 01:37:38 INFO - 'XPC_SERVICE_NAME': '0', 01:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:37:38 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 01:37:38 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 01:37:38 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 01:37:38 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 01:37:38 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 01:37:38 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 01:37:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:37:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:37:41 INFO - Installing collected packages: mozsystemmonitor 01:37:41 INFO - Running setup.py install for mozsystemmonitor 01:37:41 INFO - Successfully installed mozsystemmonitor 01:37:41 INFO - Cleaning up... 01:37:41 INFO - Return code: 0 01:37:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:41 INFO - 'HOME': '/Users/cltbld', 01:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:41 INFO - 'LOGNAME': 'cltbld', 01:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:41 INFO - 'MOZ_NO_REMOTE': '1', 01:37:41 INFO - 'NO_EM_RESTART': '1', 01:37:41 INFO - 'PAGER': '/bin/cat', 01:37:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:41 INFO - 'PWD': '/builds/slave/test', 01:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:41 INFO - 'SHELL': '/bin/bash', 01:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:41 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:41 INFO - 'USER': 'cltbld', 01:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:41 INFO - 'XPC_FLAGS': '0x0', 01:37:41 INFO - 'XPC_SERVICE_NAME': '0', 01:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:41 INFO - Downloading/unpacking jsonschema==2.5.1 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:37:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:37:44 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 01:37:44 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 01:37:44 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 01:37:44 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 01:37:44 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 01:37:44 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 01:37:44 INFO - Downloading functools32-3.2.3-2.tar.gz 01:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:37:45 INFO - warning: no files found matching '*.txt' 01:37:45 INFO - no previously-included directories found matching 'build' 01:37:45 INFO - no previously-included directories found matching 'dist' 01:37:45 INFO - no previously-included directories found matching '.git*' 01:37:45 INFO - Installing collected packages: jsonschema, functools32 01:37:45 INFO - Running setup.py install for functools32 01:37:45 INFO - warning: no files found matching '*.txt' 01:37:45 INFO - no previously-included directories found matching 'build' 01:37:45 INFO - no previously-included directories found matching 'dist' 01:37:45 INFO - no previously-included directories found matching '.git*' 01:37:45 INFO - Successfully installed jsonschema functools32 01:37:45 INFO - Cleaning up... 01:37:45 INFO - Return code: 0 01:37:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:45 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:45 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 01:37:45 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 01:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:45 INFO - 'HOME': '/Users/cltbld', 01:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:45 INFO - 'LOGNAME': 'cltbld', 01:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:45 INFO - 'MOZ_NO_REMOTE': '1', 01:37:45 INFO - 'NO_EM_RESTART': '1', 01:37:45 INFO - 'PAGER': '/bin/cat', 01:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:45 INFO - 'PWD': '/builds/slave/test', 01:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:45 INFO - 'SHELL': '/bin/bash', 01:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:45 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:45 INFO - 'USER': 'cltbld', 01:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:45 INFO - 'XPC_FLAGS': '0x0', 01:37:45 INFO - 'XPC_SERVICE_NAME': '0', 01:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:37:45 INFO - Cleaning up... 01:37:45 INFO - Return code: 0 01:37:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:37: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')]} 01:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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')]} 01:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:45 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:45 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 01:37:45 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 01:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:45 INFO - 'HOME': '/Users/cltbld', 01:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:45 INFO - 'LOGNAME': 'cltbld', 01:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:45 INFO - 'MOZ_NO_REMOTE': '1', 01:37:45 INFO - 'NO_EM_RESTART': '1', 01:37:45 INFO - 'PAGER': '/bin/cat', 01:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:45 INFO - 'PWD': '/builds/slave/test', 01:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:45 INFO - 'SHELL': '/bin/bash', 01:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:45 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:45 INFO - 'USER': 'cltbld', 01:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:45 INFO - 'XPC_FLAGS': '0x0', 01:37:45 INFO - 'XPC_SERVICE_NAME': '0', 01:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:45 INFO - Downloading/unpacking blobuploader==1.2.4 01:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:48 INFO - Downloading blobuploader-1.2.4.tar.gz 01:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:37:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:37:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:37:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 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 01:37:49 INFO - Downloading docopt-0.6.1.tar.gz 01:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:37:49 INFO - Installing collected packages: blobuploader, requests, docopt 01:37:49 INFO - Running setup.py install for blobuploader 01:37:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:37:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:37:49 INFO - Running setup.py install for requests 01:37:50 INFO - Running setup.py install for docopt 01:37:50 INFO - Successfully installed blobuploader requests docopt 01:37:50 INFO - Cleaning up... 01:37:50 INFO - Return code: 0 01:37:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:50 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')]} 01:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:50 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')]} 01:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:50 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:50 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 01:37:50 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 01:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:50 INFO - 'HOME': '/Users/cltbld', 01:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:50 INFO - 'LOGNAME': 'cltbld', 01:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:50 INFO - 'MOZ_NO_REMOTE': '1', 01:37:50 INFO - 'NO_EM_RESTART': '1', 01:37:50 INFO - 'PAGER': '/bin/cat', 01:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:50 INFO - 'PWD': '/builds/slave/test', 01:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:50 INFO - 'SHELL': '/bin/bash', 01:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:50 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:50 INFO - 'USER': 'cltbld', 01:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:50 INFO - 'XPC_FLAGS': '0x0', 01:37:50 INFO - 'XPC_SERVICE_NAME': '0', 01:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:50 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Qaq6d8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-vSoy1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-jUuXaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KSVgrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-fIOVp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-SWCAS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-q0mBEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-L8COPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-6c5o8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:51 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-4gnao_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XzBGkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-6Rpb0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-V0wvMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-UK85v1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-3v22nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XzSqEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-PfG98l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:37:52 INFO - Running setup.py install for manifestparser 01:37:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:37:52 INFO - Running setup.py install for mozcrash 01:37:53 INFO - Running setup.py install for mozdebug 01:37:53 INFO - Running setup.py install for mozdevice 01:37:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Running setup.py install for mozfile 01:37:53 INFO - Running setup.py install for mozhttpd 01:37:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Running setup.py install for mozinfo 01:37:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Running setup.py install for mozInstall 01:37:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:37:53 INFO - Running setup.py install for mozleak 01:37:54 INFO - Running setup.py install for mozlog 01:37:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:37:54 INFO - Running setup.py install for moznetwork 01:37:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:37:54 INFO - Running setup.py install for mozprocess 01:37:54 INFO - Running setup.py install for mozprofile 01:37:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:37:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:37:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:37:54 INFO - Running setup.py install for mozrunner 01:37:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:37:54 INFO - Running setup.py install for mozscreenshot 01:37:54 INFO - Running setup.py install for moztest 01:37:55 INFO - Running setup.py install for mozversion 01:37:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:37:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:37:55 INFO - Cleaning up... 01:37:55 INFO - Return code: 0 01:37:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:55 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')]} 01:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:55 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')]} 01:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:55 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:37:55 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 01:37:55 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 01:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:37:55 INFO - 'HOME': '/Users/cltbld', 01:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:37:55 INFO - 'LOGNAME': 'cltbld', 01:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:55 INFO - 'MOZ_NO_REMOTE': '1', 01:37:55 INFO - 'NO_EM_RESTART': '1', 01:37:55 INFO - 'PAGER': '/bin/cat', 01:37:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:55 INFO - 'PWD': '/builds/slave/test', 01:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:37:55 INFO - 'SHELL': '/bin/bash', 01:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:37:55 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:37:55 INFO - 'USER': 'cltbld', 01:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:55 INFO - 'XPC_FLAGS': '0x0', 01:37:55 INFO - 'XPC_SERVICE_NAME': '0', 01:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nWKZv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:55 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)) 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-gsjp4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:55 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)) 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FFrFgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:55 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)) 01:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:55 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-PHw59m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-gOhH4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KGdxAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-QqmCCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-LpXWlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wzPxtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ERH5QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-mgtcMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-yhF1yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:56 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-oVq1IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:56 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)) 01:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KZ03xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:57 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)) 01:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-03wzKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:57 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)) 01:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-PmD6K_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:57 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)) 01:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-w4mHge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:57 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)) 01:37:57 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)) 01:37:57 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)) 01:37:57 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)) 01:37:57 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)) 01:37:57 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)) 01:37:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:37: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 01:38:00 INFO - Downloading blessings-1.6.tar.gz 01:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:38:00 INFO - Installing collected packages: blessings 01:38:00 INFO - Running setup.py install for blessings 01:38:00 INFO - Successfully installed blessings 01:38:00 INFO - Cleaning up... 01:38:00 INFO - Return code: 0 01:38:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:38:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:38:00 INFO - Reading from file tmpfile_stdout 01:38:00 INFO - Current package versions: 01:38:00 INFO - blessings == 1.6 01:38:00 INFO - blobuploader == 1.2.4 01:38:00 INFO - docopt == 0.6.1 01:38:00 INFO - functools32 == 3.2.3-2 01:38:00 INFO - jsonschema == 2.5.1 01:38:00 INFO - manifestparser == 1.1 01:38:00 INFO - mozInstall == 1.12 01:38:00 INFO - mozcrash == 1.0 01:38:00 INFO - mozdebug == 0.1 01:38:00 INFO - mozdevice == 0.49 01:38:00 INFO - mozfile == 1.2 01:38:00 INFO - mozhttpd == 0.7 01:38:00 INFO - mozinfo == 0.9 01:38:00 INFO - mozleak == 0.1 01:38:00 INFO - mozlog == 3.4 01:38:00 INFO - moznetwork == 0.27 01:38:00 INFO - mozprocess == 0.24 01:38:00 INFO - mozprofile == 0.28 01:38:00 INFO - mozrunner == 6.13 01:38:00 INFO - mozscreenshot == 0.1 01:38:00 INFO - mozsystemmonitor == 0.3 01:38:00 INFO - moztest == 0.8 01:38:00 INFO - mozversion == 1.4 01:38:00 INFO - psutil == 3.1.1 01:38:00 INFO - requests == 1.2.3 01:38:00 INFO - wsgiref == 0.1.2 01:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:00 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 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:38:00 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 01:38:00 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 01:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:00 INFO - 'HOME': '/Users/cltbld', 01:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:00 INFO - 'LOGNAME': 'cltbld', 01:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:00 INFO - 'MOZ_NO_REMOTE': '1', 01:38:00 INFO - 'NO_EM_RESTART': '1', 01:38:00 INFO - 'PAGER': '/bin/cat', 01:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:00 INFO - 'PWD': '/builds/slave/test', 01:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:00 INFO - 'SHELL': '/bin/bash', 01:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:38:00 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:00 INFO - 'USER': 'cltbld', 01:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:00 INFO - 'XPC_FLAGS': '0x0', 01:38:00 INFO - 'XPC_SERVICE_NAME': '0', 01:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 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)) 01:38:00 INFO - Cleaning up... 01:38:00 INFO - Return code: 0 01:38:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a24cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a24f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc912cec3c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, '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.brZ0uDrkLj/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.V7INf5iTVz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 01:38: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'] in /builds/slave/test/build 01:38: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 01:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:00 INFO - 'HOME': '/Users/cltbld', 01:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:00 INFO - 'LOGNAME': 'cltbld', 01:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:00 INFO - 'MOZ_NO_REMOTE': '1', 01:38:00 INFO - 'NO_EM_RESTART': '1', 01:38:00 INFO - 'PAGER': '/bin/cat', 01:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:00 INFO - 'PWD': '/builds/slave/test', 01:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:00 INFO - 'SHELL': '/bin/bash', 01:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:38:00 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:00 INFO - 'USER': 'cltbld', 01:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:00 INFO - 'XPC_FLAGS': '0x0', 01:38:00 INFO - 'XPC_SERVICE_NAME': '0', 01:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:38:01 INFO - Cleaning up... 01:38:01 INFO - Return code: 0 01:38:01 INFO - Running post-action listener: _resource_record_post_action 01:38:01 INFO - Running post-action listener: _start_resource_monitoring 01:38:01 INFO - Starting resource monitoring. 01:38:01 INFO - [mozharness: 2017-02-28 09:38:01.263833Z] Finished create-virtualenv step (success) 01:38:01 INFO - [mozharness: 2017-02-28 09:38:01.264770Z] Running install step. 01:38:01 INFO - Running pre-action listener: _resource_record_pre_action 01:38:01 INFO - Running main action method: install 01:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:38:01 INFO - Reading from file tmpfile_stdout 01:38:01 INFO - Detecting whether we're running mozinstall >=1.0... 01:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:38:01 INFO - Reading from file tmpfile_stdout 01:38:01 INFO - Output received: 01:38:01 INFO - Usage: mozinstall [options] installer 01:38:01 INFO - Options: 01:38:01 INFO - -h, --help show this help message and exit 01:38:01 INFO - -d DEST, --destination=DEST 01:38:01 INFO - Directory to install application into. [default: 01:38:01 INFO - "/builds/slave/test"] 01:38:01 INFO - --app=APP Application being installed. [default: firefox] 01:38:01 INFO - mkdir: /builds/slave/test/build/application 01:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:38:21 INFO - Reading from file tmpfile_stdout 01:38:21 INFO - Output received: 01:38:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:38:21 INFO - Running post-action listener: _resource_record_post_action 01:38:21 INFO - [mozharness: 2017-02-28 09:38:21.345111Z] Finished install step (success) 01:38:21 INFO - [mozharness: 2017-02-28 09:38:21.345260Z] Running run-tests step. 01:38:21 INFO - Running pre-action listener: _resource_record_pre_action 01:38:21 INFO - Running main action method: run_tests 01:38:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:38:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:38:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:38:21 INFO - 2017-02-28 01:38:21.359 screenresolution[1711:17343] starting screenresolution argv=screenresolution get 01:38:21 INFO - 2017-02-28 01:38:21.362 screenresolution[1711:17343] Display 0: 1600x1200x32@60 01:38:21 INFO - 2017-02-28 01:38:21.368 screenresolution[1712:17346] starting screenresolution argv=screenresolution list 01:38:21 INFO - Available Modes on Display 0 01:38:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:38:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:38:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:38:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:38:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:38:21 INFO - Intel Iris: 01:38:21 INFO - Chipset Model: Intel Iris 01:38:21 INFO - Type: GPU 01:38:21 INFO - Bus: Built-In 01:38:21 INFO - VRAM (Dynamic, Max): 1536 MB 01:38:21 INFO - Vendor: Intel (0x8086) 01:38:21 INFO - Device ID: 0x0a2e 01:38:21 INFO - Revision ID: 0x0009 01:38:21 INFO - Displays: 01:38:21 INFO - Display: 01:38:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:38:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:38:21 INFO - Display Serial Number: 1600x1200 60 01:38:21 INFO - Main Display: Yes 01:38:21 INFO - Mirror: Off 01:38:21 INFO - Online: Yes 01:38:21 INFO - Rotation: Supported 01:38:21 INFO - Return code: 0 01:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:38:21 INFO - Python 2.7.3 01:38:21 INFO - Return code: 0 01:38:21 INFO - grabbing minidump binary from tooltool 01:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:21 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 0x10a02ad30>, '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 0x109f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a01fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:38:21 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 01:38:21 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 01:38:21 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 01:38:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:38:21 INFO - Return code: 0 01:38:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:38:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:38:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:38:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:38:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 01:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 01:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.brZ0uDrkLj/Render', 01:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:21 INFO - 'HOME': '/Users/cltbld', 01:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:21 INFO - 'LOGNAME': 'cltbld', 01:38:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:38:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:21 INFO - 'MOZ_NO_REMOTE': '1', 01:38:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:38:21 INFO - 'NO_EM_RESTART': '1', 01:38:21 INFO - 'PAGER': '/bin/cat', 01:38:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:21 INFO - 'PWD': '/builds/slave/test', 01:38:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:21 INFO - 'SHELL': '/bin/bash', 01:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V7INf5iTVz/Listeners', 01:38:21 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 01:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:21 INFO - 'USER': 'cltbld', 01:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:21 INFO - 'XPC_FLAGS': '0x0', 01:38:21 INFO - 'XPC_SERVICE_NAME': '0', 01:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 01:38:21 INFO - mozversion application_buildid: 20170228005036 01:38:21 INFO - mozversion application_changeset: b53997386a332c2d1f36ce2aee1feccd8ba6fc56 01:38:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:38:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:38:21 INFO - mozversion application_name: Firefox 01:38:21 INFO - mozversion application_remotingname: firefox-dev 01:38:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:38:21 INFO - mozversion application_vendor: Mozilla 01:38:21 INFO - mozversion application_version: 53.0a2 01:38:21 INFO - mozversion platform_buildid: 20170228005036 01:38:21 INFO - mozversion platform_changeset: b53997386a332c2d1f36ce2aee1feccd8ba6fc56 01:38:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:38:21 INFO - mozversion platform_version: 53.0a2 01:38:21 INFO - using testdate: 1488274701 01:38:21 INFO - actual date: 1488274701 01:38:21 INFO - starting webserver on 'localhost:49236' 01:38:21 INFO - SUITE-START | Running 2 tests 01:38:21 INFO - TEST-START | dromaeo_css 01:38:21 INFO - operating with platform_type : mac_ 01:38:21 INFO - Initialising browser for dromaeo_css test... 01:38:21 INFO - TEST-INFO | started process 1722 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpQP_k1Y/profile http://localhost:49236/getInfo.html) 01:38:24 INFO - PROCESS | 1722 | 2017-02-28 01:38:24.968 plugin-container[1726:17527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 01:38:24 INFO - PROCESS | 1722 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:38:24 INFO - PROCESS | 1722 | 2017-02-28 01:38:24.972 plugin-container[1726:17527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9703, name = 'com.apple.CFPasteboardClient' 01:38:24 INFO - PROCESS | 1722 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:38:24 INFO - PROCESS | 1722 | 2017-02-28 01:38:24.972 plugin-container[1726:17527] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:38:25 INFO - PROCESS | 1722 | __metrics Screen width/height:1600/1200 01:38:25 INFO - PROCESS | 1722 | colorDepth:24 01:38:25 INFO - PROCESS | 1722 | Browser inner width/height: 1024/689 01:38:25 INFO - PROCESS | 1722 | __metrics 01:38:25 ERROR - PROCESS | 1722 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 01:38:25 INFO - TEST-INFO | 1722: exit 0 01:38:25 INFO - Browser initialized. 01:38:25 INFO - Running cycle 1/1 for dromaeo_css test... 01:38:25 INFO - TEST-INFO | started process 1727 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpQP_k1Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:38:28 INFO - PROCESS | 1727 | 2017-02-28 01:38:28.384 plugin-container[1728:17656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 01:38:28 INFO - PROCESS | 1727 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:38:28 INFO - PROCESS | 1727 | 2017-02-28 01:38:28.386 plugin-container[1728:17656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 01:38:28 INFO - PROCESS | 1727 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:38:28 INFO - PROCESS | 1727 | 2017-02-28 01:38:28.387 plugin-container[1728:17656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:38:38 INFO - PROCESS | 1727 | RSS: Main: 218476544 01:38:38 INFO - PROCESS | 1727 | 01:41:40 INFO - PROCESS | 1727 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 01:41:42 INFO - PROCESS | 1727 | RSS: Main: 237129728 01:41:42 INFO - PROCESS | 1727 | 01:43:26 INFO - PROCESS | 1727 | ************************* 01:43:26 INFO - PROCESS | 1727 | A coding exception was thrown and uncaught in a Task. 01:43:26 INFO - PROCESS | 1727 | 01:43:26 ERROR - PROCESS | 1727 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:26 INFO - PROCESS | 1727 | Full stack: 01:43:26 INFO - PROCESS | 1727 | ************************* 01:44:44 INFO - PROCESS | 1727 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 01:44:45 INFO - PROCESS | 1727 | RSS: Main: 246075392 01:44:45 INFO - PROCESS | 1727 | 01:47:47 INFO - PROCESS | 1727 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 01:47:47 INFO - PROCESS | 1727 | RSS: Main: 253485056 01:47:47 INFO - PROCESS | 1727 | 01:50:49 INFO - PROCESS | 1727 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 01:50:50 INFO - PROCESS | 1727 | RSS: Main: 249987072 01:50:50 INFO - PROCESS | 1727 | 01:53:53 INFO - PROCESS | 1727 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 01:53:53 INFO - PROCESS | 1727 | RSS: Main: 253771776 01:53:53 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 01:56:56 INFO - PROCESS | 1727 | RSS: Main: 255893504 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | __start_tp_report 01:56:56 INFO - PROCESS | 1727 | _x_x_mozilla_page_load 01:56:56 INFO - PROCESS | 1727 | _x_x_mozilla_page_load_details 01:56:56 INFO - PROCESS | 1727 | |i|pagename|runs| 01:56:56 INFO - PROCESS | 1727 | |0;dojo.html;2821.5485522316435;3353.6814002669744;3358.9328213435747;3376.5610470638812;3400.574928133983;5754.683492407916;5759.510441612458;5783.36382997871;5801.158831969362;5803.419658034194;5135.666181698187;5324.733763311829;5715.742791574378;5806.622569532978;5845.415458454152;5243.711595862216;5266.289050978126;5314.070037743399;5333.120035194188;5352.116900711398;5367.842793192499;5369.919451208235;5370.650907691007;5384.892302153955;5396.325459317585;5179.87050323743;5204.34945631796;5342.358916929974;5348.251244825728;5350.30446042014;5221.008008478377;5231.372235331746;5283.471652834686;5288.391834938954;5327.440618735016;5187.662801917901;5253;5271.525562699337;5278.287431196778;5323.387810401815;5577.386487486373;5605.887882242373;5792.594518383715;5799.159121927339;5934.851628709273;6149.385061493859;6153.169322141498;6176.4749996250375;6181.938180618181;6197.225346831646;6246.187995560594;6248.218972628421;6252.5935814171935;6257.405546473078;6293.779717709904;5899.852503687399;5914.615549989237;5917.171595976567;5942.959982003133;5967.925773360794;6130.612163469634;6177.073438984188;6205.844853878689;6224.097505861715;6304.621722696653;6150.969245153746;6208.565400421927;6221.782237621662;6264.624122552661;6278.937210627927;6229.345918678564;6234.470070044006;6235.594686345373;6259.937400626027;6261.405166509175;6124.472479235491;6132.171952247653;6154.446099851035;6237.844053898689;6264.498840092781;5026.52248036436;6195.349488303754;6210.254769427697;6214.875702485925;6218.968905155536;4894.070126675992;4909.558139767438;4912.312276281324;4917.827876024322;4947.158982972904;4368.235558777201;4390.604845563915;4417.359482874966;4421.9829439228515;4437.0460351024685;5791.015527360359;5801.245838041028;5850.444207800252;5922.0820772779425;5956.047032474783;5214.426413094557;5222.686638801684;6286.654234017173;6299.118123462719;6300.338464461257;5649.576281778883;5727.255456790591;5823.388544202798;5825.155352473867;5933.080372542263;5503.091989821634;5528.06022976095;5534.114541673393;5537.390887002426;5579.2746942897975;6393.104965304861;6401.103845461639;6414.1340918976275;6416.615003099829;6426.710798014195;5445.673259604437;6053.516888362379;6164.660943648142;6182.443580077635;6261.154744109487;6232.906506402498;6329.082283068838;6369.585976911486;6387.105805187184;6392.169018027626;6207.099970504161;6220.440160385587;6220.471259942775;6223.844403889939;6249.7187626557825;4993.531309946831;5083.898322033612;6126.571140020156;6147.139400483847;6177.073438984188;6111.633302001894;6125.081237814364;6132.233470816148;6146.446819786241;6188.0717892314715;6125.816225513242;6161.537884658758;6181.96909015452;6184.845378865564;6207.937920620736;5990.400959903975;6022.1870047543025;6030.065339872327;6077.058056001327;6084.482818960438;5968.134620479919;6021.819345419644;6058.818235452767;6125.9693701531205;6168.105624684305;6113.081058499921;6113.14415981754;6117.357677443804;6126;6137.631742095488 01:56:56 INFO - PROCESS | 1727 | |1;ext.html;454.6612773483755;466.08879640303167;471.71461265934107;511.8464460661801;530.4721801807202;39087;41317.58682413175;41326.793366033125;43450.782746086225;43659.78170109145;40886;41284.58715412845;41314.967125821866;41321.38017929726;41542.79228603853;8286.41995060346;9225.308101892351;9445.00827413122;9460.195883349923;9494.335396522247;8212.876806847902;11267.15496337778;11496.655100347003;11513.251638643462;11682;11435.542578296858;11477.540898364055;11482;11489.655310340662;11515.078793696526;11379.943100284487;11453.312801231952;11458.427078646035;11473.082153427706;11476.4835582399;11129.054030407426;11171.553137874474;11191.440427978569;11228.15788815834;11253.71865703364;39880.40179397311;39941.20117597632;40000.999975000566;40183;40199.397009044886;3702.87039953603;3750.9437358439645;3759.943600845962;3763.435484677293;3800.3349413852466;28081.578776318373;29205.561916571267;29221.707782922113;30745.692543074507;30871.228219294473;3135.357251763383;3179.157523256339;3308.5698859148156;3334.866605335784;3337.1490269981;462336;464338;465545.9999999966;466056;466125;63972;64223.35776642267;64225.35774642287;64356.35643643504;64435.67782161059;66377.66811165914;66994.66502667456;67115.32884671188;67467.32532674709;67470.66264668645;15928.92035539815;16275.430359937345;16305.755413668805;16316.510504684878;16359.83640163607;11539.942300288445;11779.764404711857;12869.613911582668;12900.935850263913;12948.09363344557;20225.393238202876;20800;20879.269225577336;21523.67714484284;21529.923503824746;7878.448508604457;7924.732042873113;8389.525090098436;8939.061398553189;9030.503322317336;873.1879352202512;881.7266647339345;882.7837179890839;883.5361435246547;914.4193437167313;24652.260432187064;25309.367265818502;29538.409231815673;30536.7785288586;30948.381032379228;1045.4981608827652;1075.0808059109506;1092.4974511724536;1111.6720567432626;1200.0039966827512;13442.596722098353;15692.76460853088;15826.44607438734;15828;15902.840971590367;585488;586785;592330;594259;595123;48430.51569484331;59351;60695.393046069854;61123.08315375273;61158.38841611616;1420.2330741399735;1451.4121780678843;1473.506375364265;1474.2481334519473;1483.3992233145593;28079.431888452385;29877.10368688943;29954.700452995192;30193.547096794005;30239.546406803474;48928;50435.74782126066;51254;51375;51427.48572514227;524969;531504;535051;536189;539436;35240;36124.63875361318;42692.78653606712;43267.13465730731;43661.78169109134;39747.40378894261;45828.31257531073;46084.07831843411;46995.060098798516;47228.7638561805;37538.62461375351;37843.91858447812;37863.81068094642;38174;39255.41116883192;38264.426033608965;38297.23405531929;39470;39478.802605986784;39825;36383.27233455369;36941.63058369382;37312;37820.24359512849;38047.04882377962;31987.36025279528;32401.351972959936;33968.66031339655;34133.31745275563;34431.655683443845;24288.757112429357;25037;25322.493550128525;25495;25568 01:56:56 INFO - PROCESS | 1727 | |2;jquery.html;395.93071212537836;397.9005248687828;400.2675104558658;407.9678413613557;428.5307588190932;18534.44396668099;19831.116043975817;20917.37247882566;21148.259810906602;21262.362129136112;20924.686129708065;21161.259355922546;21369.786302136974;21453.463663408424;21578.352649420543;4544.61370783483;5924.526037916978;6016.6390016598925;6028.246469191351;6077.11881777142;5808.477237048664;5926.733297001624;5954.612950158247;6028.517718582503;6063.818085457421;5933.465988061073;5941.970290148543;5944.346121926585;5944.762209511614;5955.315138759055;5670.121131224688;5862.267216597926;5899.852503687443;5923.763049478037;5948.7323070461935;5196.350456192976;5231.843044708665;5232.110541208004;5234.9476505235225;5235.685858848481;24611;25759.613605795927;26972.190834275003;27322.180334589993;27466;2436.8659723715186;2599.168266154831;2654.9336266593295;2690.5964105384155;2696.379832638504;10069.89930100697;10119.747006324827;10263.69208923726;10392.700912385952;10555.788884222273;1830.752848365467;1879.1637721214065;1884.1332986826076;1901.0589758069705;1906.1898693055396;100737;130635.34682326528;132537.3373133128;146828.53171468255;150636;9783;10442.843357349646;11958.40207989597;11998.220115692626;12410.069244806675;13068.738625227632;15590.98658587211;15629.452969146025;15657.530274091796;15961.680766384607;8619;8738.694145704996;8853.247473964786;8957;9023.458592484341;8541.060483346826;8685.305175585938;8686.522241276663;8807.823843523094;8867.201951824367;4788.569028787426;4822.758862056883;4826.420829500483;4859.465458799529;4921.089598424303;2052.291959274048;2082.4897900014594;2083.9791602083783;2084.770675225724;2092.2049621144174;1016.8068067067234;1030.7320096774743;1050.6900464363032;1059.6079053893184;1062.888396718349;22058.580919957432;22366.44083897883;22421.775782242294;22424.215152469584;22457.43856403603;1051.2378525568902;1057.4606950455322;1075.693427373195;1092.2136062035481;1096.8793432722393;11452.312861228187;11503.827442588368;11602;11655.59205427806;11733.76532469363;696600;716435;734265;769921;783656;121947.39026304989;125670;146283;148925;160682;957.7318350861769;1011.5043628621775;1042.5464922758624;1044.6865940218056;1049.9527521261562;19745.90127049413;19893.701594476384;20153.496162596053;20808.27171049006;20814.791852081285;59940;60819;61909.69045154745;62068.689656551425;64307.035394468185;72642;77605;79668.60165699135;83047;86321.56839215764;7197.596468688765;7275.490715649854;7613.124490683421;8024.478408903403;8488.981322241123;10187.949060254652;12306.077044221898;13008.544700935421;13210.273434960785;13391.062625616503;11160.44197790091;11534.019608333385;11588.246763960316;11700.239484433825;11711.76576468483;11176.832347514632;11208.439578021229;11322.094232461548;11534.315486884603;11689.298642081163;11171.776564468828;11411.25826821254;11522.884771152516;11588.768224635629;11753.412329383325;7412.592307422928;7495.5127916687425;7595.278448547379;7658.693652253847;7953.5227886327;8255.834883302421;10495;11551.249168804;11801.232919860182;11948.820767688318 01:56:56 INFO - PROCESS | 1727 | |3;mootools.html;1223.2843786384965;1435.4114812926696;1443.4442739545273;1449.8835896359803;1459.9270036498176;7563.281488258621;7822.139564647899;8113.675452981881;8532.189442003008;8552.144785521452;8033.1565185655545;8212;8328;8397.286230670401;8400.201980811828;2281.4524514116574;2322.1872344679396;2329.126577533425;2329.743728189898;2332.463533387322;2091.843111766616;2175.6954026436233;2327.6392159215347;2372.359462945004;2372.406898275431;2233.6761169630477;2337.777911098443;2340.0288880114817;2345.718513778349;2352.5059737455153;2295.6671282664006;2331.7318508371586;2345.366750977235;2357.104300365867;2357.3752955466816;2167.880766557839;2232.5758105959976;2323.6630688550067;2327.7090363704538;2379.7263314718857;8306.916930830675;8374.58127093649;8396.580170991427;8398.496090234545;8404.159584041608;5071.39143302806;5210.791568337262;5218.895622087537;5233.9738301308635;5260.105782017066;3263.640999490054;3451.4650229214512;3487.9128021799406;3564.322778672044;3585.2112535242204;1163.1044096045998;1204.981925271113;1235.2032938754453;1239.4732238798565;1275.8341415616003;10517;10565.894341056568;10709.143268538575;10744.73138171552;10749.838752418642;4527.683062185615;4577.977110114494;4637.605803506673;4672.486026537078;4683.8360657376825;8754.912450875538;8765.517896515625;8824.823503530022;8827.779305517284;8853.335999800041;5057.216131499624;5100.234964755242;5102.464241254689;5104.285400043997;5109.948900511022;4559.886002849956;4759.0719809637485;4773.737444440589;4779.641526885497;4840.685355451885;3719.4606782017;3746.2132952079824;3793.430985352219;3831.8084095795098;3846.4230365445405;994.3238597753597;1095.5617752898745;1168.281506873284;1170.4206417823232;1188.0554958807768;865.6104752861112;871.5511511571545;879.2658130461009;898.3500600382501;901.1394118616705;5363.597730170253;5415.187721841755;5473.151661492475;5558.583106267046;5613.915791263134;768.1627026541097;967.1295833749682;977.9119879210905;992.6376872441507;1016.065219997604;3671.0822294426393;3687.446882967576;3716.6097559756377;3752.4183751518012;3788.0908581940134;8645.438046526955;9716.465594392235;9894.356866803635;10007.09936105754;10066.094051535398;8166.428350015561;8207.58962051907;9847.261455390875;9870.358426702385;9944.25418093646;7443.702251909863;7751.1861254568585;7808.648610812527;7859.253370929752;7870.6851725931465;5559;5657.43425657431;5670.4329567044615;5756.539476841759;5763.308402991668;8806.647734090564;9058.773530661787;9207.86188207164;9222.216111630587;9303.023182566003;9197;9341.065893410876;9402.341836071682;9436.95281523588;9495.66765163216;7051;7192.280771922766;7268.709251629875;7282.890756638761;7287.125544934642;7919.683212671659;8011.959940200261;8034.076081250731;8047.959760201162;8067.354611631173;7001.649917504206;7108.111486064242;7112.466565007749;7173.175084865307;7260.419166466776;6984.114289142005;6993.335633114846;7014.473914456538;7040.507164498641;7069.646517673993;6322.905156422566;6340.71466784005;6394.584352017104;6411.743530258737;6424;4873.975630121827;4919.139150648693;4924.138275801792;4926.876828079184;5000.774965126505;3812.294725475685;4875.1712208923855;4879.878003049952;5015.2978582997675;5114.232865070269 01:56:56 INFO - PROCESS | 1727 | |4;prototype.html;351.00665117716864;365.91949771050366;366.9688076513496;367.55525813765325;370.2317690791607;4361.498427680817;4617.930731039037;5246.580273578114;5426.9185962210595;5534.252875861763;4811.903761924768;4977.452480227172;4979.651424400294;5182.833862380962;5418.729063546826;4247.044415006614;4267.594578515044;4288.871333859989;4296.548862369453;4356.259435895905;3461.9094985079746;3659.4144936810117;3745.8127093645207;4289.785510724451;4329.805158767863;3392.5928888533285;4035.2938235808765;4056.837726490937;4076.653484453825;4105.466289382391;4072.450219220412;4074.7164643137316;4074.7351422157467;4080.5511393746665;4084.346504559286;2837.8648540583727;3034.6813584573524;3160.683931606821;3532.9155798079255;3568.928621427583;4237.8304867805255;4990.3762029746285;5282.969820884929;5328.760205790749;5331.3213882421005;4041.4139949707424;4385.495667998157;4402.933955990663;4675.5558221969195;4699.519651309826;5680.602357834953;5713.74288157034;5714.314282286152;5730.541556675456;5741.655500669932;3301.7028467438045;3684.852605895761;3862.671672907806;3908.257431088084;3916;5394.73026348681;6048.576599638027;6061.605995610271;6090.299615544225;6094.8171554853425;3560.913921254042;3832.5017747692623;3960.3069462843887;3963.672169823084;3969.3053715599654;3555.1645363339594;3710.962890371116;3873.360895452275;4007.156707914392;4015.9357770190923;4174.77038762871;4285;4661.184292748755;4683.227267500893;4700.388949436619;4681.8361357353015;5362.973185134049;5786.594938354359;5840.415958404211;5862.325832529228;1090.1496832470527;1160.442987366069;1247.7941139711845;1289.2844471318508;1295.3587973952765;864.204931463043;879.1735768377705;881.4887365328223;887.3788348156187;887.9034392525226;5135.35808023997;5135.743212839343;5135.794568217304;5149.716765577929;5166.457521960216;5003.649744517922;5235.9266350398075;5302.946970530323;5305.018571564273;5634.85912852182;4088.366303223005;4767.332573439721;5096.770645320969;5097.1844504879045;5141.511556402211;2590.4689538644534;2926.736593706534;2928.9853550732537;2983.9552406713915;2990.6710261871176;5359.383670877821;5628.731166492921;5708.40061793514;5834.824955251349;5862.824115276549;5340.783574816408;5375.75809088592;5524.569189556582;5675.574331925122;5770.4806567408295;4640.466346370211;5249.448807875118;5658.066419040812;5905.970470147622;5915.319738230159;5288.762005709676;5311.5485183758265;5468.781248750005;5663.603547751618;5665.745041473061;4653.790579423866;5231.346081739783;5624.943750562606;5681.324009417149;5818.003728079958;4552.521985191507;5272.420033796356;5731.8853622928145;5816.389279125631;5900.881982360414;3439.3121375725455;3533.734969877218;3741;3748.7001039916195;3760.9435858461597;4788.760561971957;5166.696630111258;5383.339329256143;5518.448155184449;5547.195656629686;4597.563231493003;5232.895342093213;5273.683578985273;5505.311836020497;5510.448955104618;4636.536346365337;4669.533046695304;5350.438203988525;5403.405625381283;5526.364468086222;5026.170681837456;5101;5144.742762861767;5442.918356224739;5672.659640421588;5030.4718004608985;5591.748371323381;5748.166515855261;5757.351618255482;5766.106253530709;4794.291225457605;5416.891662166813;5457.317835270591;5631.521320687787;5635.8591035224445 01:56:56 INFO - PROCESS | 1727 | |5;yui.html;690.0236165825357;715.6600614708015;748.1882157858723;774.6668932359083;859.4499520307005;12498.375081245935;14739.778903316432;15158.772618410732;15195;15399.69200615987;12205.450754716037;14175.574732758008;14429.494967676133;14444.638884027878;14458.349374278156;835.3567752830317;864.654138344664;866.5060915278294;867.540203692041;868.3617541107282;669.0800149794034;690.9136357955256;856.7301300090483;861.0743450790394;861.3410740783306;896.157611844867;917.4541148016953;927.8144371125768;929.2612373163317;932.7947851472699;901.9098090190961;917.2432742987062;917.3486824354716;920.1672486399785;921.8340698674236;690.0477341269067;695.766918082443;696.4498046543225;697.6407150317591;698.2458944340101;14682.63293417673;15170.772438413433;15549.455769048142;15551.688966220612;15716;14123.152610843383;14371.640708982255;14438.422463101464;14598.635034124125;14633.195174265411;11399.48702308398;11617.103151262554;11671.766564668744;11701.3564253966;11722.179447438682;3596.940669785396;3654.671079602822;3696.9349899161025;3701.7223708221723;3728.5712143103347;106668;106850;109725;109993.35009974777;110599.9999999992;10925.180611454172;10940.671779846618;11094.278871873303;11557.422128893522;11694.590689325832;23422.009129224207;23568.64647030296;24084.27747167588;24120;24484.02063917459;7716.961415192888;7736.187700291501;7747.109082455477;7821.491603045783;7877.251661092187;3859.517560304962;4113.382992551082;4200.579942005775;4246.915061698749;4447.444069491314;3337.566116404852;3346.1801858544814;3620.3664358737115;3625.9818700906853;3640.890773276806;639.9936000640027;653.2716021635841;663.2140913017993;663.9634820084846;703.6130128429446;633.1863555331377;660.9173853268342;664.3058004385332;677.16957000228;695.4366962760181;8538.316934645214;9848.803023939481;10272.024157705006;10326.690199294033;10410.843837342445;663.1875951958812;680.2279412866433;696.4324075878244;705.9047028651066;708.9716411343488;4335.544767799335;4595.517470665599;5011.824586139468;5023.79904803811;5043.52086551777;87685.56157219173;99569.50215248877;103014.48492757489;104251;106682;94573;97015.51492242493;98263.03473930624;98844.50577747209;99525;70069.64965175142;84591;84779.57610212032;86828.56585717031;86875.13124868796;9584.457420973029;10989.61536346224;10997.450127493434;11298.548058077586;11359.772804544029;38226.426603601525;50162.996740065726;51393.48606513887;52069;52497.737511312196;69434.65282673555;70028;70310.24224394227;70995;72264.9160262607;12348.3825808711;12460.501579936701;12655;12721.618351449471;12756.170848894792;13829.723405531631;14385.712285754436;14404.207768572829;14601.12393256408;14793.964422490753;12441.564545240873;13083.934580327037;13260.668483287609;13301.135426197266;13692.45230190821;12565.308908010147;13208.471661133448;13351.799723004357;13528.873141310316;13678.247696376795;12329.136960412685;12916.354182291036;13032.413541390471;13454.394552244976;13488.460461581426;10721.088707459954;10853.457327133454;10999.395033273247;11038.944805275922;11714.004309633776;9529.475878826453;9647.276454265817;10803.243772935819;10803.675889723321;10834.241603087708 01:56:56 INFO - PROCESS | 1727 | __end_tp_report 01:56:56 INFO - PROCESS | 1727 | __start_cc_report 01:56:56 INFO - PROCESS | 1727 | _x_x_mozilla_cycle_collect,390 01:56:56 INFO - PROCESS | 1727 | __end_cc_report 01:56:56 INFO - PROCESS | 1727 | __startTimestamp1488275816806__endTimestamp 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | ------- Summary: start ------- 01:56:56 INFO - PROCESS | 1727 | Number of tests: 6 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | [#0] dojo.html Cycles:165 Average:5747.56 Median:5990.40 stddev:654.03 (10.9%) stddev-sans-first:614.44 01:56:56 INFO - PROCESS | 1727 | Values: 2821.5 3353.7 3358.9 3376.6 3400.6 5754.7 5759.5 5783.4 5801.2 5803.4 5135.7 5324.7 5715.7 5806.6 5845.4 5243.7 5266.3 5314.1 5333.1 5352.1 5367.8 5369.9 5370.7 5384.9 5396.3 5179.9 5204.3 5342.4 5348.3 5350.3 5221.0 5231.4 5283.5 5288.4 5327.4 5187.7 5253.0 5271.5 5278.3 5323.4 5577.4 5605.9 5792.6 5799.2 5934.9 6149.4 6153.2 6176.5 6181.9 6197.2 6246.2 6248.2 6252.6 6257.4 6293.8 5899.9 5914.6 5917.2 5943.0 5967.9 6130.6 6177.1 6205.8 6224.1 6304.6 6151.0 6208.6 6221.8 6264.6 6278.9 6229.3 6234.5 6235.6 6259.9 6261.4 6124.5 6132.2 6154.4 6237.8 6264.5 5026.5 6195.3 6210.3 6214.9 6219.0 4894.1 4909.6 4912.3 4917.8 4947.2 4368.2 4390.6 4417.4 4422.0 4437.0 5791.0 5801.2 5850.4 5922.1 5956.0 5214.4 5222.7 6286.7 6299.1 6300.3 5649.6 5727.3 5823.4 5825.2 5933.1 5503.1 5528.1 5534.1 5537.4 5579.3 6393.1 6401.1 6414.1 6416.6 6426.7 5445.7 6053.5 6164.7 6182.4 6261.2 6232.9 6329.1 6369.6 6387.1 6392.2 6207.1 6220.4 6220.5 6223.8 6249.7 4993.5 5083.9 6126.6 6147.1 6177.1 6111.6 6125.1 6132.2 6146.4 6188.1 6125.8 6161.5 6182.0 6184.8 6207.9 5990.4 6022.2 6030.1 6077.1 6084.5 5968.1 6021.8 6058.8 6126.0 6168.1 6113.1 6113.1 6117.4 6126.0 6137.6 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | [#1] ext.html Cycles:180 Average:67687.39 Median:37853.86 stddev:141679.10 (374.3%) stddev-sans-first:141986.62 01:56:56 INFO - PROCESS | 1727 | Values: 454.7 466.1 471.7 511.8 530.5 39087.0 41317.6 41326.8 43450.8 43659.8 40886.0 41284.6 41315.0 41321.4 41542.8 8286.4 9225.3 9445.0 9460.2 9494.3 8212.9 11267.2 11496.7 11513.3 11682.0 11435.5 11477.5 11482.0 11489.7 11515.1 11379.9 11453.3 11458.4 11473.1 11476.5 11129.1 11171.6 11191.4 11228.2 11253.7 39880.4 39941.2 40001.0 40183.0 40199.4 3702.9 3750.9 3759.9 3763.4 3800.3 28081.6 29205.6 29221.7 30745.7 30871.2 3135.4 3179.2 3308.6 3334.9 3337.1 462336.0 464338.0 465546.0 466056.0 466125.0 63972.0 64223.4 64225.4 64356.4 64435.7 66377.7 66994.7 67115.3 67467.3 67470.7 15928.9 16275.4 16305.8 16316.5 16359.8 11539.9 11779.8 12869.6 12900.9 12948.1 20225.4 20800.0 20879.3 21523.7 21529.9 7878.4 7924.7 8389.5 8939.1 9030.5 873.2 881.7 882.8 883.5 914.4 24652.3 25309.4 29538.4 30536.8 30948.4 1045.5 1075.1 1092.5 1111.7 1200.0 13442.6 15692.8 15826.4 15828.0 15902.8 585488.0 586785.0 592330.0 594259.0 595123.0 48430.5 59351.0 60695.4 61123.1 61158.4 1420.2 1451.4 1473.5 1474.2 1483.4 28079.4 29877.1 29954.7 30193.5 30239.5 48928.0 50435.7 51254.0 51375.0 51427.5 524969.0 531504.0 535051.0 536189.0 539436.0 35240.0 36124.6 42692.8 43267.1 43661.8 39747.4 45828.3 46084.1 46995.1 47228.8 37538.6 37843.9 37863.8 38174.0 39255.4 38264.4 38297.2 39470.0 39478.8 39825.0 36383.3 36941.6 37312.0 37820.2 38047.0 31987.4 32401.4 33968.7 34133.3 34431.7 24288.8 25037.0 25322.5 25495.0 25568.0 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | [#2] jquery.html Cycles:180 Average:40224.84 Median:21515.91 stddev:123149.14 (572.4%) stddev-sans-first:123458.29 01:56:56 INFO - PROCESS | 1727 | Values: 395.9 397.9 400.3 408.0 428.5 18534.4 19831.1 20917.4 21148.3 21262.4 20924.7 21161.3 21369.8 21453.5 21578.4 4544.6 5924.5 6016.6 6028.2 6077.1 5808.5 5926.7 5954.6 6028.5 6063.8 5933.5 5942.0 5944.3 5944.8 5955.3 5670.1 5862.3 5899.9 5923.8 5948.7 5196.4 5231.8 5232.1 5234.9 5235.7 24611.0 25759.6 26972.2 27322.2 27466.0 2436.9 2599.2 2654.9 2690.6 2696.4 10069.9 10119.7 10263.7 10392.7 10555.8 1830.8 1879.2 1884.1 1901.1 1906.2 100737.0 130635.3 132537.3 146828.5 150636.0 9783.0 10442.8 11958.4 11998.2 12410.1 13068.7 15591.0 15629.5 15657.5 15961.7 8619.0 8738.7 8853.2 8957.0 9023.5 8541.1 8685.3 8686.5 8807.8 8867.2 4788.6 4822.8 4826.4 4859.5 4921.1 2052.3 2082.5 2084.0 2084.8 2092.2 1016.8 1030.7 1050.7 1059.6 1062.9 22058.6 22366.4 22421.8 22424.2 22457.4 1051.2 1057.5 1075.7 1092.2 1096.9 11452.3 11503.8 11602.0 11655.6 11733.8 696600.0 716435.0 734265.0 769921.0 783656.0 121947.4 125670.0 146283.0 148925.0 160682.0 957.7 1011.5 1042.5 1044.7 1050.0 19745.9 19893.7 20153.5 20808.3 20814.8 59940.0 60819.0 61909.7 62068.7 64307.0 72642.0 77605.0 79668.6 83047.0 86321.6 7197.6 7275.5 7613.1 8024.5 8489.0 10187.9 12306.1 13008.5 13210.3 13391.1 11160.4 11534.0 11588.2 11700.2 11711.8 11176.8 11208.4 11322.1 11534.3 11689.3 11171.8 11411.3 11522.9 11588.8 11753.4 7412.6 7495.5 7595.3 7658.7 7953.5 8255.8 10495.0 11551.2 11801.2 11948.8 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | [#3] mootools.html Cycles:180 Average:5380.23 Median:5247.04 stddev:2898.20 (55.2%) stddev-sans-first:2889.49 01:56:56 INFO - PROCESS | 1727 | Values: 1223.3 1435.4 1443.4 1449.9 1459.9 7563.3 7822.1 8113.7 8532.2 8552.1 8033.2 8212.0 8328.0 8397.3 8400.2 2281.5 2322.2 2329.1 2329.7 2332.5 2091.8 2175.7 2327.6 2372.4 2372.4 2233.7 2337.8 2340.0 2345.7 2352.5 2295.7 2331.7 2345.4 2357.1 2357.4 2167.9 2232.6 2323.7 2327.7 2379.7 8306.9 8374.6 8396.6 8398.5 8404.2 5071.4 5210.8 5218.9 5234.0 5260.1 3263.6 3451.5 3487.9 3564.3 3585.2 1163.1 1205.0 1235.2 1239.5 1275.8 10517.0 10565.9 10709.1 10744.7 10749.8 4527.7 4578.0 4637.6 4672.5 4683.8 8754.9 8765.5 8824.8 8827.8 8853.3 5057.2 5100.2 5102.5 5104.3 5109.9 4559.9 4759.1 4773.7 4779.6 4840.7 3719.5 3746.2 3793.4 3831.8 3846.4 994.3 1095.6 1168.3 1170.4 1188.1 865.6 871.6 879.3 898.4 901.1 5363.6 5415.2 5473.2 5558.6 5613.9 768.2 967.1 977.9 992.6 1016.1 3671.1 3687.4 3716.6 3752.4 3788.1 8645.4 9716.5 9894.4 10007.1 10066.1 8166.4 8207.6 9847.3 9870.4 9944.3 7443.7 7751.2 7808.6 7859.3 7870.7 5559.0 5657.4 5670.4 5756.5 5763.3 8806.6 9058.8 9207.9 9222.2 9303.0 9197.0 9341.1 9402.3 9437.0 9495.7 7051.0 7192.3 7268.7 7282.9 7287.1 7919.7 8012.0 8034.1 8048.0 8067.4 7001.6 7108.1 7112.5 7173.2 7260.4 6984.1 6993.3 7014.5 7040.5 7069.6 6322.9 6340.7 6394.6 6411.7 6424.0 4874.0 4919.1 4924.1 4926.9 5000.8 3812.3 4875.2 4879.9 5015.3 5114.2 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | [#4] prototype.html Cycles:180 Average:4479.47 Median:5063.62 stddev:1376.78 (27.2%) stddev-sans-first:1345.32 01:56:56 INFO - PROCESS | 1727 | Values: 351.0 365.9 367.0 367.6 370.2 4361.5 4617.9 5246.6 5426.9 5534.3 4811.9 4977.5 4979.7 5182.8 5418.7 4247.0 4267.6 4288.9 4296.5 4356.3 3461.9 3659.4 3745.8 4289.8 4329.8 3392.6 4035.3 4056.8 4076.7 4105.5 4072.5 4074.7 4074.7 4080.6 4084.3 2837.9 3034.7 3160.7 3532.9 3568.9 4237.8 4990.4 5283.0 5328.8 5331.3 4041.4 4385.5 4402.9 4675.6 4699.5 5680.6 5713.7 5714.3 5730.5 5741.7 3301.7 3684.9 3862.7 3908.3 3916.0 5394.7 6048.6 6061.6 6090.3 6094.8 3560.9 3832.5 3960.3 3963.7 3969.3 3555.2 3711.0 3873.4 4007.2 4015.9 4174.8 4285.0 4661.2 4683.2 4700.4 4681.8 5363.0 5786.6 5840.4 5862.3 1090.1 1160.4 1247.8 1289.3 1295.4 864.2 879.2 881.5 887.4 887.9 5135.4 5135.7 5135.8 5149.7 5166.5 5003.6 5235.9 5302.9 5305.0 5634.9 4088.4 4767.3 5096.8 5097.2 5141.5 2590.5 2926.7 2929.0 2984.0 2990.7 5359.4 5628.7 5708.4 5834.8 5862.8 5340.8 5375.8 5524.6 5675.6 5770.5 4640.5 5249.4 5658.1 5906.0 5915.3 5288.8 5311.5 5468.8 5663.6 5665.7 4653.8 5231.3 5624.9 5681.3 5818.0 4552.5 5272.4 5731.9 5816.4 5900.9 3439.3 3533.7 3741.0 3748.7 3760.9 4788.8 5166.7 5383.3 5518.4 5547.2 4597.6 5232.9 5273.7 5505.3 5510.4 4636.5 4669.5 5350.4 5403.4 5526.4 5026.2 5101.0 5144.7 5442.9 5672.7 5030.5 5591.7 5748.2 5757.4 5766.1 4794.3 5416.9 5457.3 5631.5 5635.9 01:56:56 INFO - PROCESS | 1727 | 01:56:56 INFO - PROCESS | 1727 | [#5] yui.html Cycles:180 Average:20926.21 Median:3699.33 stddev:30416.87 (822.2%) stddev-sans-first:30464.25 01:56:56 INFO - PROCESS | 1727 | Values: 690.0 715.7 748.2 774.7 859.4 12498.4 14739.8 15158.8 15195.0 15399.7 12205.5 14175.6 14429.5 14444.6 14458.3 835.4 864.7 866.5 867.5 868.4 669.1 690.9 856.7 861.1 861.3 896.2 917.5 927.8 929.3 932.8 901.9 917.2 917.3 920.2 921.8 690.0 695.8 696.4 697.6 698.2 14682.6 15170.8 15549.5 15551.7 15716.0 14123.2 14371.6 14438.4 14598.6 14633.2 11399.5 11617.1 11671.8 11701.4 11722.2 3596.9 3654.7 3696.9 3701.7 3728.6 106668.0 106850.0 109725.0 109993.4 110600.0 10925.2 10940.7 11094.3 11557.4 11694.6 23422.0 23568.6 24084.3 24120.0 24484.0 7717.0 7736.2 7747.1 7821.5 7877.3 3859.5 4113.4 4200.6 4246.9 4447.4 3337.6 3346.2 3620.4 3626.0 3640.9 640.0 653.3 663.2 664.0 703.6 633.2 660.9 664.3 677.2 695.4 8538.3 9848.8 10272.0 10326.7 10410.8 663.2 680.2 696.4 705.9 709.0 4335.5 4595.5 5011.8 5023.8 5043.5 87685.6 99569.5 103014.5 104251.0 106682.0 94573.0 97015.5 98263.0 98844.5 99525.0 70069.6 84591.0 84779.6 86828.6 86875.1 9584.5 10989.6 10997.5 11298.5 11359.8 38226.4 50163.0 51393.5 52069.0 52497.7 69434.7 70028.0 70310.2 70995.0 72264.9 12348.4 12460.5 12655.0 12721.6 12756.2 13829.7 14385.7 14404.2 14601.1 14794.0 12441.6 13083.9 13260.7 13301.1 13692.5 12565.3 13208.5 13351.8 13528.9 13678.2 12329.1 12916.4 13032.4 13454.4 13488.5 10721.1 10853.5 10999.4 11038.9 11714.0 9529.5 9647.3 10803.2 10803.7 10834.2 01:56:56 INFO - PROCESS | 1727 | -------- Summary: end -------- 01:56:56 INFO - PROCESS | 1727 | 01:56:57 INFO - TEST-INFO | 1727: exit 0 01:56:57 INFO - TEST-OK | dromaeo_css | took 1115458ms 01:56:57 INFO - TEST-START | kraken 01:56:57 INFO - operating with platform_type : mac_ 01:56:57 INFO - Initialising browser for kraken test... 01:56:57 INFO - TEST-INFO | started process 1733 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpe1l8y7/profile http://localhost:49236/getInfo.html) 01:57:00 INFO - PROCESS | 1733 | 2017-02-28 01:57:00.144 plugin-container[1734:19815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 01:57:00 INFO - PROCESS | 1733 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:57:00 INFO - PROCESS | 1733 | 2017-02-28 01:57:00.147 plugin-container[1734:19815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 01:57:00 INFO - PROCESS | 1733 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:57:00 INFO - PROCESS | 1733 | 2017-02-28 01:57:00.147 plugin-container[1734:19815] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:57:00 INFO - PROCESS | 1733 | __metrics Screen width/height:1600/1200 01:57:00 INFO - PROCESS | 1733 | colorDepth:24 01:57:00 INFO - PROCESS | 1733 | Browser inner width/height: 1024/689 01:57:00 INFO - PROCESS | 1733 | __metrics 01:57:00 ERROR - PROCESS | 1733 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 01:57:00 INFO - TEST-INFO | 1733: exit 0 01:57:00 INFO - Browser initialized. 01:57:00 INFO - Running cycle 1/1 for kraken test... 01:57:00 INFO - TEST-INFO | started process 1735 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpe1l8y7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:57:03 INFO - PROCESS | 1735 | 2017-02-28 01:57:03.269 plugin-container[1736:19943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 01:57:03 INFO - PROCESS | 1735 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:57:03 INFO - PROCESS | 1735 | 2017-02-28 01:57:03.270 plugin-container[1736:19943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 01:57:03 INFO - PROCESS | 1735 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:57:03 INFO - PROCESS | 1735 | 2017-02-28 01:57:03.270 plugin-container[1736:19943] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:57:12 INFO - PROCESS | 1735 | RSS: Main: 218583040 01:57:12 INFO - PROCESS | 1735 | 01:57:15 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 01:57:16 INFO - PROCESS | 1735 | RSS: Main: 233316352 01:57:16 INFO - PROCESS | 1735 | 01:57:19 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 01:57:19 INFO - PROCESS | 1735 | RSS: Main: 240947200 01:57:19 INFO - PROCESS | 1735 | 01:57:23 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 01:57:24 INFO - PROCESS | 1735 | RSS: Main: 244703232 01:57:24 INFO - PROCESS | 1735 | 01:57:25 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 01:57:26 INFO - PROCESS | 1735 | RSS: Main: 245768192 01:57:26 INFO - PROCESS | 1735 | 01:57:28 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:57:29 INFO - PROCESS | 1735 | RSS: Main: 245657600 01:57:29 INFO - PROCESS | 1735 | 01:57:31 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 01:57:32 INFO - PROCESS | 1735 | RSS: Main: 250482688 01:57:32 INFO - PROCESS | 1735 | 01:57:35 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 01:57:35 INFO - PROCESS | 1735 | RSS: Main: 250875904 01:57:35 INFO - PROCESS | 1735 | 01:57:39 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 01:57:39 INFO - PROCESS | 1735 | RSS: Main: 250019840 01:57:39 INFO - PROCESS | 1735 | 01:57:41 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:57:42 INFO - PROCESS | 1735 | RSS: Main: 248844288 01:57:42 INFO - PROCESS | 1735 | 01:57:43 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:57:44 INFO - PROCESS | 1735 | RSS: Main: 248909824 01:57:44 INFO - PROCESS | 1735 | 01:57:46 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:57:47 INFO - PROCESS | 1735 | RSS: Main: 248901632 01:57:47 INFO - PROCESS | 1735 | 01:57:50 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:57:51 INFO - PROCESS | 1735 | RSS: Main: 248922112 01:57:51 INFO - PROCESS | 1735 | 01:57:54 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:57:54 INFO - PROCESS | 1735 | RSS: Main: 249225216 01:57:54 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 01:57:56 INFO - PROCESS | 1735 | RSS: Main: 251150336 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | __start_tp_report 01:57:56 INFO - PROCESS | 1735 | _x_x_mozilla_page_load 01:57:56 INFO - PROCESS | 1735 | _x_x_mozilla_page_load_details 01:57:56 INFO - PROCESS | 1735 | |i|pagename|runs| 01:57:56 INFO - PROCESS | 1735 | |0;ai-astar;117;119;119;117;120;119;119;118;118;118 01:57:56 INFO - PROCESS | 1735 | |1;audio-beat-detection;145;129;146;182;98;100;139;145;160;149 01:57:56 INFO - PROCESS | 1735 | |2;audio-dft;195;200;195;200;195;194;236;283;195;261 01:57:56 INFO - PROCESS | 1735 | |3;audio-fft;80;58;60;82;59;60;59;60;69;59 01:57:56 INFO - PROCESS | 1735 | |4;audio-oscillator;87;71;86;72;70;71;80;70;70;82 01:57:56 INFO - PROCESS | 1735 | |5;imaging-gaussian-blur;87;86;90;89;88;84;88;86;90;86 01:57:56 INFO - PROCESS | 1735 | |6;imaging-darkroom;95;104;154;95;95;96;96;96;95;94 01:57:56 INFO - PROCESS | 1735 | |7;imaging-desaturate;95;132;150;113;93;94;96;92;113;152 01:57:56 INFO - PROCESS | 1735 | |8;json-parse-financial;60;59;58;62;62;61;63;62;62;63 01:57:56 INFO - PROCESS | 1735 | |9;json-stringify-tinderbox;68;76;69;68;75;75;74;68;72;75 01:57:56 INFO - PROCESS | 1735 | |10;stanford-crypto-aes;84;85;81;94;101;102;110;97;85;87 01:57:56 INFO - PROCESS | 1735 | |11;stanford-crypto-ccm;133;157;155;158;134;138;155;143;186;162 01:57:56 INFO - PROCESS | 1735 | |12;stanford-crypto-pbkdf2;175;171;172;173;171;169;168;176;236;209 01:57:56 INFO - PROCESS | 1735 | |13;stanford-crypto-sha256-iterative;67;68;67;67;69;82;88;88;80;65 01:57:56 INFO - PROCESS | 1735 | __end_tp_report 01:57:56 INFO - PROCESS | 1735 | __start_cc_report 01:57:56 INFO - PROCESS | 1735 | _x_x_mozilla_cycle_collect,984 01:57:56 INFO - PROCESS | 1735 | __end_cc_report 01:57:56 INFO - PROCESS | 1735 | __startTimestamp1488275876890__endTimestamp 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | ------- Summary: start ------- 01:57:56 INFO - PROCESS | 1735 | Number of tests: 14 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#0] ai-astar Cycles:10 Average:118.40 Median:119.00 stddev:0.97 (0.8%) stddev-sans-first:0.88 01:57:56 INFO - PROCESS | 1735 | Values: 117.0 119.0 119.0 117.0 120.0 119.0 119.0 118.0 118.0 118.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:147.50 stddev:25.41 (17.2%) stddev-sans-first:26.87 01:57:56 INFO - PROCESS | 1735 | Values: 145.0 129.0 146.0 182.0 98.0 100.0 139.0 145.0 160.0 149.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#2] audio-dft Cycles:10 Average:215.40 Median:200.00 stddev:32.78 (16.4%) stddev-sans-first:33.93 01:57:56 INFO - PROCESS | 1735 | Values: 195.0 200.0 195.0 200.0 195.0 194.0 236.0 283.0 195.0 261.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#3] audio-fft Cycles:10 Average:64.60 Median:60.00 stddev:9.19 (15.3%) stddev-sans-first:7.88 01:57:56 INFO - PROCESS | 1735 | Values: 80.0 58.0 60.0 82.0 59.0 60.0 59.0 60.0 69.0 59.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:76.00 stddev:7.05 (9.3%) stddev-sans-first:6.22 01:57:56 INFO - PROCESS | 1735 | Values: 87.0 71.0 86.0 72.0 70.0 71.0 80.0 70.0 70.0 82.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.00 stddev:1.96 (2.2%) stddev-sans-first:2.07 01:57:56 INFO - PROCESS | 1735 | Values: 87.0 86.0 90.0 89.0 88.0 84.0 88.0 86.0 90.0 86.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:95.00 stddev:18.49 (19.5%) stddev-sans-first:19.43 01:57:56 INFO - PROCESS | 1735 | Values: 95.0 104.0 154.0 95.0 95.0 96.0 96.0 96.0 95.0 94.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#7] imaging-desaturate Cycles:10 Average:113.00 Median:92.50 stddev:23.73 (25.6%) stddev-sans-first:24.25 01:57:56 INFO - PROCESS | 1735 | Values: 95.0 132.0 150.0 113.0 93.0 94.0 96.0 92.0 113.0 152.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:62.00 stddev:1.69 (2.7%) stddev-sans-first:1.73 01:57:56 INFO - PROCESS | 1735 | Values: 60.0 59.0 58.0 62.0 62.0 61.0 63.0 62.0 62.0 63.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:74.50 stddev:3.40 (4.6%) stddev-sans-first:3.28 01:57:56 INFO - PROCESS | 1735 | Values: 68.0 76.0 69.0 68.0 75.0 75.0 74.0 68.0 72.0 75.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:85.00 stddev:9.65 (11.4%) stddev-sans-first:9.72 01:57:56 INFO - PROCESS | 1735 | Values: 84.0 85.0 81.0 94.0 101.0 102.0 110.0 97.0 85.0 87.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:156.00 stddev:15.98 (10.2%) stddev-sans-first:15.38 01:57:56 INFO - PROCESS | 1735 | Values: 133.0 157.0 155.0 158.0 134.0 138.0 155.0 143.0 186.0 162.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:174.00 stddev:22.41 (12.9%) stddev-sans-first:23.62 01:57:56 INFO - PROCESS | 1735 | Values: 175.0 171.0 172.0 173.0 171.0 169.0 168.0 176.0 236.0 209.0 01:57:56 INFO - PROCESS | 1735 | 01:57:56 INFO - PROCESS | 1735 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:74.50 stddev:9.31 (12.5%) stddev-sans-first:9.52 01:57:56 INFO - PROCESS | 1735 | Values: 67.0 68.0 67.0 67.0 69.0 82.0 88.0 88.0 80.0 65.0 01:57:56 INFO - PROCESS | 1735 | -------- Summary: end -------- 01:57:56 INFO - PROCESS | 1735 | 01:57:57 INFO - TEST-INFO | 1735: exit 0 01:57:57 INFO - TEST-OK | kraken | took 60359ms 01:57:57 INFO - SUITE-END | took 1175s 01:57:58 INFO - Completed test suite (00:19:36) 01:57:58 INFO - javascript benchmark 01:57:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7346.2377374952, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2821.5485522316435, 3353.6814002669744, 3358.9328213435747, 3376.5610470638812, 3400.574928133983, 5754.683492407916, 5759.510441612458, 5783.36382997871, 5801.158831969362, 5803.419658034194, 5135.666181698187, 5324.733763311829, 5715.742791574378, 5806.622569532978, 5845.415458454152, 5243.711595862216, 5266.289050978126, 5314.070037743399, 5333.120035194188, 5352.116900711398, 5367.842793192499, 5369.919451208235, 5370.650907691007, 5384.892302153955, 5396.325459317585, 5179.87050323743, 5204.34945631796, 5342.358916929974, 5348.251244825728, 5350.30446042014, 5221.008008478377, 5231.372235331746, 5283.471652834686, 5288.391834938954, 5327.440618735016, 5187.662801917901, 5253.0, 5271.525562699337, 5278.287431196778, 5323.387810401815, 5577.386487486373, 5605.887882242373, 5792.594518383715, 5799.159121927339, 5934.851628709273, 6149.385061493859, 6153.169322141498, 6176.4749996250375, 6181.938180618181, 6197.225346831646, 6246.187995560594, 6248.218972628421, 6252.5935814171935, 6257.405546473078, 6293.779717709904, 5899.852503687399, 5914.615549989237, 5917.171595976567, 5942.959982003133, 5967.925773360794, 6130.612163469634, 6177.073438984188, 6205.844853878689, 6224.097505861715, 6304.621722696653, 6150.969245153746, 6208.565400421927, 6221.782237621662, 6264.624122552661, 6278.937210627927, 6229.345918678564, 6234.470070044006, 6235.594686345373, 6259.937400626027, 6261.405166509175, 6124.472479235491, 6132.171952247653, 6154.446099851035, 6237.844053898689, 6264.498840092781, 5026.52248036436, 6195.349488303754, 6210.254769427697, 6214.875702485925, 6218.968905155536, 4894.070126675992, 4909.558139767438, 4912.312276281324, 4917.827876024322, 4947.158982972904, 4368.235558777201, 4390.604845563915, 4417.359482874966, 4421.9829439228515, 4437.0460351024685, 5791.015527360359, 5801.245838041028, 5850.444207800252, 5922.0820772779425, 5956.047032474783, 5214.426413094557, 5222.686638801684, 6286.654234017173, 6299.118123462719, 6300.338464461257, 5649.576281778883, 5727.255456790591, 5823.388544202798, 5825.155352473867, 5933.080372542263, 5503.091989821634, 5528.06022976095, 5534.114541673393, 5537.390887002426, 5579.2746942897975, 6393.104965304861, 6401.103845461639, 6414.1340918976275, 6416.615003099829, 6426.710798014195, 5445.673259604437, 6053.516888362379, 6164.660943648142, 6182.443580077635, 6261.154744109487, 6232.906506402498, 6329.082283068838, 6369.585976911486, 6387.105805187184, 6392.169018027626, 6207.099970504161, 6220.440160385587, 6220.471259942775, 6223.844403889939, 6249.7187626557825, 4993.531309946831, 5083.898322033612, 6126.571140020156, 6147.139400483847, 6177.073438984188, 6111.633302001894, 6125.081237814364, 6132.233470816148, 6146.446819786241, 6188.0717892314715, 6125.816225513242, 6161.537884658758, 6181.96909015452, 6184.845378865564, 6207.937920620736, 5990.400959903975, 6022.1870047543025, 6030.065339872327, 6077.058056001327, 6084.482818960438, 5968.134620479919, 6021.819345419644, 6058.818235452767, 6125.9693701531205, 6168.105624684305, 6113.081058499921, 6113.14415981754, 6117.357677443804, 6126.0, 6137.631742095488], "value": 5705.733471202883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.6612773483755, 466.08879640303167, 471.71461265934107, 511.8464460661801, 530.4721801807202, 39087.0, 41317.58682413175, 41326.793366033125, 43450.782746086225, 43659.78170109145, 40886.0, 41284.58715412845, 41314.967125821866, 41321.38017929726, 41542.79228603853, 8286.41995060346, 9225.308101892351, 9445.00827413122, 9460.195883349923, 9494.335396522247, 8212.876806847902, 11267.15496337778, 11496.655100347003, 11513.251638643462, 11682.0, 11435.542578296858, 11477.540898364055, 11482.0, 11489.655310340662, 11515.078793696526, 11379.943100284487, 11453.312801231952, 11458.427078646035, 11473.082153427706, 11476.4835582399, 11129.054030407426, 11171.553137874474, 11191.440427978569, 11228.15788815834, 11253.71865703364, 39880.40179397311, 39941.20117597632, 40000.999975000566, 40183.0, 40199.397009044886, 3702.87039953603, 3750.9437358439645, 3759.943600845962, 3763.435484677293, 3800.3349413852466, 28081.578776318373, 29205.561916571267, 29221.707782922113, 30745.692543074507, 30871.228219294473, 3135.357251763383, 3179.157523256339, 3308.5698859148156, 3334.866605335784, 3337.1490269981, 462336.0, 464338.0, 465545.9999999966, 466056.0, 466125.0, 63972.0, 64223.35776642267, 64225.35774642287, 64356.35643643504, 64435.67782161059, 66377.66811165914, 66994.66502667456, 67115.32884671188, 67467.32532674709, 67470.66264668645, 15928.92035539815, 16275.430359937345, 16305.755413668805, 16316.510504684878, 16359.83640163607, 11539.942300288445, 11779.764404711857, 12869.613911582668, 12900.935850263913, 12948.09363344557, 20225.393238202876, 20800.0, 20879.269225577336, 21523.67714484284, 21529.923503824746, 7878.448508604457, 7924.732042873113, 8389.525090098436, 8939.061398553189, 9030.503322317336, 873.1879352202512, 881.7266647339345, 882.7837179890839, 883.5361435246547, 914.4193437167313, 24652.260432187064, 25309.367265818502, 29538.409231815673, 30536.7785288586, 30948.381032379228, 1045.4981608827652, 1075.0808059109506, 1092.4974511724536, 1111.6720567432626, 1200.0039966827512, 13442.596722098353, 15692.76460853088, 15826.44607438734, 15828.0, 15902.840971590367, 585488.0, 586785.0, 592330.0, 594259.0, 595123.0, 48430.51569484331, 59351.0, 60695.393046069854, 61123.08315375273, 61158.38841611616, 1420.2330741399735, 1451.4121780678843, 1473.506375364265, 1474.2481334519473, 1483.3992233145593, 28079.431888452385, 29877.10368688943, 29954.700452995192, 30193.547096794005, 30239.546406803474, 48928.0, 50435.74782126066, 51254.0, 51375.0, 51427.48572514227, 524969.0, 531504.0, 535051.0, 536189.0, 539436.0, 35240.0, 36124.63875361318, 42692.78653606712, 43267.13465730731, 43661.78169109134, 39747.40378894261, 45828.31257531073, 46084.07831843411, 46995.060098798516, 47228.7638561805, 37538.62461375351, 37843.91858447812, 37863.81068094642, 38174.0, 39255.41116883192, 38264.426033608965, 38297.23405531929, 39470.0, 39478.802605986784, 39825.0, 36383.27233455369, 36941.63058369382, 37312.0, 37820.24359512849, 38047.04882377962, 31987.36025279528, 32401.351972959936, 33968.66031339655, 34133.31745275563, 34431.655683443845, 24288.757112429357, 25037.0, 25322.493550128525, 25495.0, 25568.0], "value": 20558.188668948806, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.93071212537836, 397.9005248687828, 400.2675104558658, 407.9678413613557, 428.5307588190932, 18534.44396668099, 19831.116043975817, 20917.37247882566, 21148.259810906602, 21262.362129136112, 20924.686129708065, 21161.259355922546, 21369.786302136974, 21453.463663408424, 21578.352649420543, 4544.61370783483, 5924.526037916978, 6016.6390016598925, 6028.246469191351, 6077.11881777142, 5808.477237048664, 5926.733297001624, 5954.612950158247, 6028.517718582503, 6063.818085457421, 5933.465988061073, 5941.970290148543, 5944.346121926585, 5944.762209511614, 5955.315138759055, 5670.121131224688, 5862.267216597926, 5899.852503687443, 5923.763049478037, 5948.7323070461935, 5196.350456192976, 5231.843044708665, 5232.110541208004, 5234.9476505235225, 5235.685858848481, 24611.0, 25759.613605795927, 26972.190834275003, 27322.180334589993, 27466.0, 2436.8659723715186, 2599.168266154831, 2654.9336266593295, 2690.5964105384155, 2696.379832638504, 10069.89930100697, 10119.747006324827, 10263.69208923726, 10392.700912385952, 10555.788884222273, 1830.752848365467, 1879.1637721214065, 1884.1332986826076, 1901.0589758069705, 1906.1898693055396, 100737.0, 130635.34682326528, 132537.3373133128, 146828.53171468255, 150636.0, 9783.0, 10442.843357349646, 11958.40207989597, 11998.220115692626, 12410.069244806675, 13068.738625227632, 15590.98658587211, 15629.452969146025, 15657.530274091796, 15961.680766384607, 8619.0, 8738.694145704996, 8853.247473964786, 8957.0, 9023.458592484341, 8541.060483346826, 8685.305175585938, 8686.522241276663, 8807.823843523094, 8867.201951824367, 4788.569028787426, 4822.758862056883, 4826.420829500483, 4859.465458799529, 4921.089598424303, 2052.291959274048, 2082.4897900014594, 2083.9791602083783, 2084.770675225724, 2092.2049621144174, 1016.8068067067234, 1030.7320096774743, 1050.6900464363032, 1059.6079053893184, 1062.888396718349, 22058.580919957432, 22366.44083897883, 22421.775782242294, 22424.215152469584, 22457.43856403603, 1051.2378525568902, 1057.4606950455322, 1075.693427373195, 1092.2136062035481, 1096.8793432722393, 11452.312861228187, 11503.827442588368, 11602.0, 11655.59205427806, 11733.76532469363, 696600.0, 716435.0, 734265.0, 769921.0, 783656.0, 121947.39026304989, 125670.0, 146283.0, 148925.0, 160682.0, 957.7318350861769, 1011.5043628621775, 1042.5464922758624, 1044.6865940218056, 1049.9527521261562, 19745.90127049413, 19893.701594476384, 20153.496162596053, 20808.27171049006, 20814.791852081285, 59940.0, 60819.0, 61909.69045154745, 62068.689656551425, 64307.035394468185, 72642.0, 77605.0, 79668.60165699135, 83047.0, 86321.56839215764, 7197.596468688765, 7275.490715649854, 7613.124490683421, 8024.478408903403, 8488.981322241123, 10187.949060254652, 12306.077044221898, 13008.544700935421, 13210.273434960785, 13391.062625616503, 11160.44197790091, 11534.019608333385, 11588.246763960316, 11700.239484433825, 11711.76576468483, 11176.832347514632, 11208.439578021229, 11322.094232461548, 11534.315486884603, 11689.298642081163, 11171.776564468828, 11411.25826821254, 11522.884771152516, 11588.768224635629, 11753.412329383325, 7412.592307422928, 7495.5127916687425, 7595.278448547379, 7658.693652253847, 7953.5227886327, 8255.834883302421, 10495.0, 11551.249168804, 11801.232919860182, 11948.820767688318], "value": 9987.014607306588, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1223.2843786384965, 1435.4114812926696, 1443.4442739545273, 1449.8835896359803, 1459.9270036498176, 7563.281488258621, 7822.139564647899, 8113.675452981881, 8532.189442003008, 8552.144785521452, 8033.1565185655545, 8212.0, 8328.0, 8397.286230670401, 8400.201980811828, 2281.4524514116574, 2322.1872344679396, 2329.126577533425, 2329.743728189898, 2332.463533387322, 2091.843111766616, 2175.6954026436233, 2327.6392159215347, 2372.359462945004, 2372.406898275431, 2233.6761169630477, 2337.777911098443, 2340.0288880114817, 2345.718513778349, 2352.5059737455153, 2295.6671282664006, 2331.7318508371586, 2345.366750977235, 2357.104300365867, 2357.3752955466816, 2167.880766557839, 2232.5758105959976, 2323.6630688550067, 2327.7090363704538, 2379.7263314718857, 8306.916930830675, 8374.58127093649, 8396.580170991427, 8398.496090234545, 8404.159584041608, 5071.39143302806, 5210.791568337262, 5218.895622087537, 5233.9738301308635, 5260.105782017066, 3263.640999490054, 3451.4650229214512, 3487.9128021799406, 3564.322778672044, 3585.2112535242204, 1163.1044096045998, 1204.981925271113, 1235.2032938754453, 1239.4732238798565, 1275.8341415616003, 10517.0, 10565.894341056568, 10709.143268538575, 10744.73138171552, 10749.838752418642, 4527.683062185615, 4577.977110114494, 4637.605803506673, 4672.486026537078, 4683.8360657376825, 8754.912450875538, 8765.517896515625, 8824.823503530022, 8827.779305517284, 8853.335999800041, 5057.216131499624, 5100.234964755242, 5102.464241254689, 5104.285400043997, 5109.948900511022, 4559.886002849956, 4759.0719809637485, 4773.737444440589, 4779.641526885497, 4840.685355451885, 3719.4606782017, 3746.2132952079824, 3793.430985352219, 3831.8084095795098, 3846.4230365445405, 994.3238597753597, 1095.5617752898745, 1168.281506873284, 1170.4206417823232, 1188.0554958807768, 865.6104752861112, 871.5511511571545, 879.2658130461009, 898.3500600382501, 901.1394118616705, 5363.597730170253, 5415.187721841755, 5473.151661492475, 5558.583106267046, 5613.915791263134, 768.1627026541097, 967.1295833749682, 977.9119879210905, 992.6376872441507, 1016.065219997604, 3671.0822294426393, 3687.446882967576, 3716.6097559756377, 3752.4183751518012, 3788.0908581940134, 8645.438046526955, 9716.465594392235, 9894.356866803635, 10007.09936105754, 10066.094051535398, 8166.428350015561, 8207.58962051907, 9847.261455390875, 9870.358426702385, 9944.25418093646, 7443.702251909863, 7751.1861254568585, 7808.648610812527, 7859.253370929752, 7870.6851725931465, 5559.0, 5657.43425657431, 5670.4329567044615, 5756.539476841759, 5763.308402991668, 8806.647734090564, 9058.773530661787, 9207.86188207164, 9222.216111630587, 9303.023182566003, 9197.0, 9341.065893410876, 9402.341836071682, 9436.95281523588, 9495.66765163216, 7051.0, 7192.280771922766, 7268.709251629875, 7282.890756638761, 7287.125544934642, 7919.683212671659, 8011.959940200261, 8034.076081250731, 8047.959760201162, 8067.354611631173, 7001.649917504206, 7108.111486064242, 7112.466565007749, 7173.175084865307, 7260.419166466776, 6984.114289142005, 6993.335633114846, 7014.473914456538, 7040.507164498641, 7069.646517673993, 6322.905156422566, 6340.71466784005, 6394.584352017104, 6411.743530258737, 6424.0, 4873.975630121827, 4919.139150648693, 4924.138275801792, 4926.876828079184, 5000.774965126505, 3812.294725475685, 4875.1712208923855, 4879.878003049952, 5015.2978582997675, 5114.232865070269], "value": 4381.129196980522, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.00665117716864, 365.91949771050366, 366.9688076513496, 367.55525813765325, 370.2317690791607, 4361.498427680817, 4617.930731039037, 5246.580273578114, 5426.9185962210595, 5534.252875861763, 4811.903761924768, 4977.452480227172, 4979.651424400294, 5182.833862380962, 5418.729063546826, 4247.044415006614, 4267.594578515044, 4288.871333859989, 4296.548862369453, 4356.259435895905, 3461.9094985079746, 3659.4144936810117, 3745.8127093645207, 4289.785510724451, 4329.805158767863, 3392.5928888533285, 4035.2938235808765, 4056.837726490937, 4076.653484453825, 4105.466289382391, 4072.450219220412, 4074.7164643137316, 4074.7351422157467, 4080.5511393746665, 4084.346504559286, 2837.8648540583727, 3034.6813584573524, 3160.683931606821, 3532.9155798079255, 3568.928621427583, 4237.8304867805255, 4990.3762029746285, 5282.969820884929, 5328.760205790749, 5331.3213882421005, 4041.4139949707424, 4385.495667998157, 4402.933955990663, 4675.5558221969195, 4699.519651309826, 5680.602357834953, 5713.74288157034, 5714.314282286152, 5730.541556675456, 5741.655500669932, 3301.7028467438045, 3684.852605895761, 3862.671672907806, 3908.257431088084, 3916.0, 5394.73026348681, 6048.576599638027, 6061.605995610271, 6090.299615544225, 6094.8171554853425, 3560.913921254042, 3832.5017747692623, 3960.3069462843887, 3963.672169823084, 3969.3053715599654, 3555.1645363339594, 3710.962890371116, 3873.360895452275, 4007.156707914392, 4015.9357770190923, 4174.77038762871, 4285.0, 4661.184292748755, 4683.227267500893, 4700.388949436619, 4681.8361357353015, 5362.973185134049, 5786.594938354359, 5840.415958404211, 5862.325832529228, 1090.1496832470527, 1160.442987366069, 1247.7941139711845, 1289.2844471318508, 1295.3587973952765, 864.204931463043, 879.1735768377705, 881.4887365328223, 887.3788348156187, 887.9034392525226, 5135.35808023997, 5135.743212839343, 5135.794568217304, 5149.716765577929, 5166.457521960216, 5003.649744517922, 5235.9266350398075, 5302.946970530323, 5305.018571564273, 5634.85912852182, 4088.366303223005, 4767.332573439721, 5096.770645320969, 5097.1844504879045, 5141.511556402211, 2590.4689538644534, 2926.736593706534, 2928.9853550732537, 2983.9552406713915, 2990.6710261871176, 5359.383670877821, 5628.731166492921, 5708.40061793514, 5834.824955251349, 5862.824115276549, 5340.783574816408, 5375.75809088592, 5524.569189556582, 5675.574331925122, 5770.4806567408295, 4640.466346370211, 5249.448807875118, 5658.066419040812, 5905.970470147622, 5915.319738230159, 5288.762005709676, 5311.5485183758265, 5468.781248750005, 5663.603547751618, 5665.745041473061, 4653.790579423866, 5231.346081739783, 5624.943750562606, 5681.324009417149, 5818.003728079958, 4552.521985191507, 5272.420033796356, 5731.8853622928145, 5816.389279125631, 5900.881982360414, 3439.3121375725455, 3533.734969877218, 3741.0, 3748.7001039916195, 3760.9435858461597, 4788.760561971957, 5166.696630111258, 5383.339329256143, 5518.448155184449, 5547.195656629686, 4597.563231493003, 5232.895342093213, 5273.683578985273, 5505.311836020497, 5510.448955104618, 4636.536346365337, 4669.533046695304, 5350.438203988525, 5403.405625381283, 5526.364468086222, 5026.170681837456, 5101.0, 5144.742762861767, 5442.918356224739, 5672.659640421588, 5030.4718004608985, 5591.748371323381, 5748.166515855261, 5757.351618255482, 5766.106253530709, 4794.291225457605, 5416.891662166813, 5457.317835270591, 5631.521320687787, 5635.8591035224445], "value": 4055.4482543843874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.0236165825357, 715.6600614708015, 748.1882157858723, 774.6668932359083, 859.4499520307005, 12498.375081245935, 14739.778903316432, 15158.772618410732, 15195.0, 15399.69200615987, 12205.450754716037, 14175.574732758008, 14429.494967676133, 14444.638884027878, 14458.349374278156, 835.3567752830317, 864.654138344664, 866.5060915278294, 867.540203692041, 868.3617541107282, 669.0800149794034, 690.9136357955256, 856.7301300090483, 861.0743450790394, 861.3410740783306, 896.157611844867, 917.4541148016953, 927.8144371125768, 929.2612373163317, 932.7947851472699, 901.9098090190961, 917.2432742987062, 917.3486824354716, 920.1672486399785, 921.8340698674236, 690.0477341269067, 695.766918082443, 696.4498046543225, 697.6407150317591, 698.2458944340101, 14682.63293417673, 15170.772438413433, 15549.455769048142, 15551.688966220612, 15716.0, 14123.152610843383, 14371.640708982255, 14438.422463101464, 14598.635034124125, 14633.195174265411, 11399.48702308398, 11617.103151262554, 11671.766564668744, 11701.3564253966, 11722.179447438682, 3596.940669785396, 3654.671079602822, 3696.9349899161025, 3701.7223708221723, 3728.5712143103347, 106668.0, 106850.0, 109725.0, 109993.35009974777, 110599.9999999992, 10925.180611454172, 10940.671779846618, 11094.278871873303, 11557.422128893522, 11694.590689325832, 23422.009129224207, 23568.64647030296, 24084.27747167588, 24120.0, 24484.02063917459, 7716.961415192888, 7736.187700291501, 7747.109082455477, 7821.491603045783, 7877.251661092187, 3859.517560304962, 4113.382992551082, 4200.579942005775, 4246.915061698749, 4447.444069491314, 3337.566116404852, 3346.1801858544814, 3620.3664358737115, 3625.9818700906853, 3640.890773276806, 639.9936000640027, 653.2716021635841, 663.2140913017993, 663.9634820084846, 703.6130128429446, 633.1863555331377, 660.9173853268342, 664.3058004385332, 677.16957000228, 695.4366962760181, 8538.316934645214, 9848.803023939481, 10272.024157705006, 10326.690199294033, 10410.843837342445, 663.1875951958812, 680.2279412866433, 696.4324075878244, 705.9047028651066, 708.9716411343488, 4335.544767799335, 4595.517470665599, 5011.824586139468, 5023.79904803811, 5043.52086551777, 87685.56157219173, 99569.50215248877, 103014.48492757489, 104251.0, 106682.0, 94573.0, 97015.51492242493, 98263.03473930624, 98844.50577747209, 99525.0, 70069.64965175142, 84591.0, 84779.57610212032, 86828.56585717031, 86875.13124868796, 9584.457420973029, 10989.61536346224, 10997.450127493434, 11298.548058077586, 11359.772804544029, 38226.426603601525, 50162.996740065726, 51393.48606513887, 52069.0, 52497.737511312196, 69434.65282673555, 70028.0, 70310.24224394227, 70995.0, 72264.9160262607, 12348.3825808711, 12460.501579936701, 12655.0, 12721.618351449471, 12756.170848894792, 13829.723405531631, 14385.712285754436, 14404.207768572829, 14601.12393256408, 14793.964422490753, 12441.564545240873, 13083.934580327037, 13260.668483287609, 13301.135426197266, 13692.45230190821, 12565.308908010147, 13208.471661133448, 13351.799723004357, 13528.873141310316, 13678.247696376795, 12329.136960412685, 12916.354182291036, 13032.413541390471, 13454.394552244976, 13488.460461581426, 10721.088707459954, 10853.457327133454, 10999.395033273247, 11038.944805275922, 11714.004309633776, 9529.475878826453, 9647.276454265817, 10803.243772935819, 10803.675889723321, 10834.241603087708], "value": 7550.63008339376, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 119.0, 117.0, 120.0, 119.0, 119.0, 118.0, 118.0, 118.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 129.0, 146.0, 182.0, 98.0, 100.0, 139.0, 145.0, 160.0, 149.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 195.0, 200.0, 195.0, 194.0, 236.0, 283.0, 195.0, 261.0], "value": 215.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 82.0, 59.0, 60.0, 59.0, 60.0, 69.0, 59.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 86.0, 72.0, 70.0, 71.0, 80.0, 70.0, 70.0, 82.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 90.0, 89.0, 88.0, 84.0, 88.0, 86.0, 90.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 104.0, 154.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 94.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 132.0, 150.0, 113.0, 93.0, 94.0, 96.0, 92.0, 113.0, 152.0], "value": 113.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 62.0, 62.0, 61.0, 63.0, 62.0, 62.0, 63.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 69.0, 68.0, 75.0, 75.0, 74.0, 68.0, 72.0, 75.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 81.0, 94.0, 101.0, 102.0, 110.0, 97.0, 85.0, 87.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 157.0, 155.0, 158.0, 134.0, 138.0, 155.0, 143.0, 186.0, 162.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 172.0, 173.0, 171.0, 169.0, 168.0, 176.0, 236.0, 209.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 69.0, 82.0, 88.0, 88.0, 80.0, 65.0], "value": 74.1, "unit": "score"}]}]} 01:57:58 INFO - Return code: 0 01:57:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:57:58 ERROR - # TBPL SUCCESS # 01:57:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:57:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:57:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:57:58 INFO - 2017-02-28 01:57:58.194 screenresolution[1740:20325] starting screenresolution argv=screenresolution get 01:57:58 INFO - 2017-02-28 01:57:58.196 screenresolution[1740:20325] Display 0: 1600x1200x32@60 01:57:58 INFO - 2017-02-28 01:57:58.202 screenresolution[1741:20328] starting screenresolution argv=screenresolution list 01:57:58 INFO - Available Modes on Display 0 01:57:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:57:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:57:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:57:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:57:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:57:58 INFO - Intel Iris: 01:57:58 INFO - Chipset Model: Intel Iris 01:57:58 INFO - Type: GPU 01:57:58 INFO - Bus: Built-In 01:57:58 INFO - VRAM (Dynamic, Max): 1536 MB 01:57:58 INFO - Vendor: Intel (0x8086) 01:57:58 INFO - Device ID: 0x0a2e 01:57:58 INFO - Revision ID: 0x0009 01:57:58 INFO - Displays: 01:57:58 INFO - Display: 01:57:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:57:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:57:58 INFO - Display Serial Number: 1600x1200 60 01:57:58 INFO - Main Display: Yes 01:57:58 INFO - Mirror: Off 01:57:58 INFO - Online: Yes 01:57:58 INFO - Rotation: Supported 01:57:58 INFO - Return code: 0 01:57:58 INFO - Running post-action listener: _resource_record_post_action 01:57:58 INFO - [mozharness: 2017-02-28 09:57:58.437835Z] Finished run-tests step (success) 01:57:58 INFO - Running post-run listener: _resource_record_post_run 01:57:58 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 33210880; Write bytes: 514132480; Read time: 196; Write time: 1607 01:57:58 INFO - TinderboxPrint: CPU usage
54.5% 01:57:58 INFO - TinderboxPrint: I/O read bytes / time
33,210,880 / 196 01:57:58 INFO - TinderboxPrint: I/O write bytes / time
514,132,480 / 1,607 01:57:58 INFO - TinderboxPrint: CPU idle
2,178.6 (45.5%) 01:57:58 INFO - TinderboxPrint: CPU system
964.8 (20.2%) 01:57:58 INFO - TinderboxPrint: CPU user
1,642.3 (34.3%) 01:57:58 INFO - TinderboxPrint: Swap in / out
251,363,328 / 0 01:57:58 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209046016; Write bytes: 212183040; Read time: 15973; Write time: 463 01:57:58 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29651456; Write bytes: 300360192; Read time: 172; Write time: 1137 01:57:58 INFO - Running post-run listener: _upload_blobber_files 01:57:58 INFO - Blob upload gear active. 01:57:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:57:58 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 01:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:57:59 INFO - (blobuploader) - INFO - Open directory for files ... 01:57:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:57:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:00 INFO - (blobuploader) - INFO - Done attempting. 01:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:01 INFO - (blobuploader) - INFO - Done attempting. 01:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:02 INFO - (blobuploader) - INFO - Done attempting. 01:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:58:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:04 INFO - (blobuploader) - INFO - Done attempting. 01:58:04 INFO - (blobuploader) - INFO - Iteration through files over. 01:58:04 INFO - Return code: 0 01:58:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:58:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:58:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c59d44b1019f1ae628e02f9767a5ed1653cf5c4ea2aa519f5ff7a8e9c99dbc7bd4bf7cdf63f5147507d42d3aaf355aabe255af5c8df0fdf49c245c8844d891b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93ff7e48c989ba83bac8beb008beb4f7bb959a36c40cf14cd1fc78c24384d0b61e3d70b0c6383791fd8c983b8ce5c5fbd45c57744f65a11262c4f8959a91a0da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5dec4b8dd72cb92eaf649e058dc12889cb2666bfceab8936cd6060c055350fa24718c70d4073e861d60cf6aee8c00414edacdec5a991f35ccdb467bf8c4c9d5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c67b0b89b72884a11aa1c639837115ad5dadbde4e990b030248b535f4794af8ce8e07bb363af0f1eeb525d3ff9c91251c69bd5de9eb2e1e4f80af639c1dfacc"} 01:58:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:58:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:58:04 INFO - Contents: 01:58:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c59d44b1019f1ae628e02f9767a5ed1653cf5c4ea2aa519f5ff7a8e9c99dbc7bd4bf7cdf63f5147507d42d3aaf355aabe255af5c8df0fdf49c245c8844d891b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93ff7e48c989ba83bac8beb008beb4f7bb959a36c40cf14cd1fc78c24384d0b61e3d70b0c6383791fd8c983b8ce5c5fbd45c57744f65a11262c4f8959a91a0da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5dec4b8dd72cb92eaf649e058dc12889cb2666bfceab8936cd6060c055350fa24718c70d4073e861d60cf6aee8c00414edacdec5a991f35ccdb467bf8c4c9d5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c67b0b89b72884a11aa1c639837115ad5dadbde4e990b030248b535f4794af8ce8e07bb363af0f1eeb525d3ff9c91251c69bd5de9eb2e1e4f80af639c1dfacc"} 01:58:04 INFO - Running post-run listener: copy_logs_to_upload_dir 01:58:04 INFO - Copying logs to upload dir... 01:58:04 INFO - mkdir: /builds/slave/test/build/upload/logs 01:58:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.765304 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-02-28 01:58:04.910170) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-28 01:58:04.913818) ========= 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.brZ0uDrkLj/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.V7INf5iTVz/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c59d44b1019f1ae628e02f9767a5ed1653cf5c4ea2aa519f5ff7a8e9c99dbc7bd4bf7cdf63f5147507d42d3aaf355aabe255af5c8df0fdf49c245c8844d891b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93ff7e48c989ba83bac8beb008beb4f7bb959a36c40cf14cd1fc78c24384d0b61e3d70b0c6383791fd8c983b8ce5c5fbd45c57744f65a11262c4f8959a91a0da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5dec4b8dd72cb92eaf649e058dc12889cb2666bfceab8936cd6060c055350fa24718c70d4073e861d60cf6aee8c00414edacdec5a991f35ccdb467bf8c4c9d5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c67b0b89b72884a11aa1c639837115ad5dadbde4e990b030248b535f4794af8ce8e07bb363af0f1eeb525d3ff9c91251c69bd5de9eb2e1e4f80af639c1dfacc"} build_url:https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/dN3ssu9lS-6GPoNH3Ek0iQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c59d44b1019f1ae628e02f9767a5ed1653cf5c4ea2aa519f5ff7a8e9c99dbc7bd4bf7cdf63f5147507d42d3aaf355aabe255af5c8df0fdf49c245c8844d891b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93ff7e48c989ba83bac8beb008beb4f7bb959a36c40cf14cd1fc78c24384d0b61e3d70b0c6383791fd8c983b8ce5c5fbd45c57744f65a11262c4f8959a91a0da", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5dec4b8dd72cb92eaf649e058dc12889cb2666bfceab8936cd6060c055350fa24718c70d4073e861d60cf6aee8c00414edacdec5a991f35ccdb467bf8c4c9d5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5c67b0b89b72884a11aa1c639837115ad5dadbde4e990b030248b535f4794af8ce8e07bb363af0f1eeb525d3ff9c91251c69bd5de9eb2e1e4f80af639c1dfacc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-28 01:58:04.949326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-02-28 01:58:04.949623) ========= 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.brZ0uDrkLj/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.V7INf5iTVz/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005144 ========= master_lag: 1.44 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-02-28 01:58:06.398829) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-28 01:58:06.399157) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-28 01:58:06.399732) ========= ========= Total master_lag: 1.65 =========