builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0202 starttime: 1482817296.18 results: success (0) buildid: 20161226205936 builduid: defa6e964a894aa096dbd479828fbf6e revision: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.180795) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.181335) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.181620) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.408580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.408960) ========= 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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 --2016-12-26 21:41:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 176M=0s 2016-12-26 21:41:36 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.542585 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.979222) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:36.979538) ========= 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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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.024639 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:37.027936) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 21:41:37.028633) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bZqdHAvo6e/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.0O3ak5RX1B/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 2016-12-26 21:41:37,131 truncating revision to first 12 chars 2016-12-26 21:41:37,131 Setting DEBUG logging. 2016-12-26 21:41:37,131 attempt 1/10 2016-12-26 21:41:37,131 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4a1f36ebd21c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 21:41:38,231 unpacking tar archive at: mozilla-central-4a1f36ebd21c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.374868 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 21:41:38.428980) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:38.429306) ========= script_repo_revision: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:38.429693) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:38.429959) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 21:41:38.449614) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-26 21:41:38.449939) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bZqdHAvo6e/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.0O3ak5RX1B/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:41:38 INFO - MultiFileLogger online at 20161226 21:41:38 in /builds/slave/test 21:41:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:41:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:41:38 INFO - {'append_to_log': False, 21:41:38 INFO - 'base_work_dir': '/builds/slave/test', 21:41:38 INFO - 'blob_upload_branch': 'Firefox', 21:41:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:41:38 INFO - 'branch': 'Firefox', 21:41:38 INFO - 'buildbot_json_path': 'buildprops.json', 21:41:38 INFO - 'config_files': ('talos/mac_config.py',), 21:41:38 INFO - 'default_actions': ('clobber', 21:41:38 INFO - 'read-buildbot-config', 21:41:38 INFO - 'download-and-extract', 21:41:38 INFO - 'populate-webroot', 21:41:38 INFO - 'create-virtualenv', 21:41:38 INFO - 'install', 21:41:38 INFO - 'run-tests'), 21:41:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:41:38 INFO - 'download_minidump_stackwalk': True, 21:41:38 INFO - 'download_symbols': 'ondemand', 21:41:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:41:38 INFO - 'tooltool.py': '/tools/tooltool.py', 21:41:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:41:38 INFO - '/tools/misc-python/virtualenv.py')}, 21:41:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:41:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:41:38 INFO - 'installer_path': 'installer.exe', 21:41:38 INFO - 'log_level': 'info', 21:41:38 INFO - 'log_name': 'talos', 21:41:38 INFO - 'log_to_console': True, 21:41:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:41:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:41:38 INFO - 'opt_config_files': (), 21:41:38 INFO - 'pip_index': False, 21:41:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:41:38 INFO - 'cmd': ('bash', 21:41:38 INFO - '-c', 21:41:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:41:38 INFO - 'enabled': True, 21:41:38 INFO - 'halt_on_failure': False, 21:41:38 INFO - 'name': 'check_screen_resolution'},), 21:41:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:41:38 INFO - 'cmd': ('bash', 21:41:38 INFO - '-c', 21:41:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:41:38 INFO - 'enabled': True, 21:41:38 INFO - 'halt_on_failure': False, 21:41:38 INFO - 'name': 'check_screen_resolution'},), 21:41:38 INFO - 'run_cmd_checks_enabled': True, 21:41:38 INFO - 'sps_profile': False, 21:41:38 INFO - 'sps_profile_interval': 0, 21:41:38 INFO - 'suite': 'dromaeojs', 21:41:38 INFO - 'system_bits': '32', 21:41:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:41:38 INFO - 'title': 't-yosemite-r7-0202', 21:41:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:41:38 INFO - 'use_talos_json': True, 21:41:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:41:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:41:38 INFO - 'work_dir': 'build'} 21:41:38 INFO - [mozharness: 2016-12-27 05:41:38.655899Z] Running clobber step. 21:41:38 INFO - Running pre-action listener: _resource_record_pre_action 21:41:38 INFO - Running main action method: clobber 21:41:38 INFO - rmtree: /builds/slave/test/build 21:41:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:41:40 INFO - Running post-action listener: _resource_record_post_action 21:41:40 INFO - [mozharness: 2016-12-27 05:41:40.976517Z] Finished clobber step (success) 21:41:40 INFO - [mozharness: 2016-12-27 05:41:40.976643Z] Running read-buildbot-config step. 21:41:40 INFO - Running pre-action listener: _resource_record_pre_action 21:41:40 INFO - Running main action method: read_buildbot_config 21:41:40 INFO - Using buildbot properties: 21:41:40 INFO - { 21:41:40 INFO - "project": "", 21:41:40 INFO - "product": "firefox", 21:41:40 INFO - "script_repo_revision": "production", 21:41:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 21:41:40 INFO - "repository": "", 21:41:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 21:41:40 INFO - "buildid": "20161226205936", 21:41:40 INFO - "pgo_build": "False", 21:41:40 INFO - "basedir": "/builds/slave/test", 21:41:40 INFO - "buildnumber": 162, 21:41:40 INFO - "slavename": "t-yosemite-r7-0202", 21:41:40 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 21:41:40 INFO - "platform": "yosemite_r7", 21:41:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 21:41:40 INFO - "branch": "mozilla-central", 21:41:40 INFO - "repo_path": "mozilla-central", 21:41:40 INFO - "revision": "4a1f36ebd21cbe090cd5d0ce6458af3d883a868a", 21:41:40 INFO - "stage_platform": "macosx64", 21:41:40 INFO - "builduid": "defa6e964a894aa096dbd479828fbf6e", 21:41:40 INFO - "slavebuilddir": "test" 21:41:40 INFO - } 21:41:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:41:40 INFO - Running post-action listener: _resource_record_post_action 21:41:40 INFO - [mozharness: 2016-12-27 05:41:40.980171Z] Finished read-buildbot-config step (success) 21:41:40 INFO - [mozharness: 2016-12-27 05:41:40.980312Z] Running download-and-extract step. 21:41:40 INFO - Running pre-action listener: _resource_record_pre_action 21:41:40 INFO - Running main action method: download_and_extract 21:41:40 INFO - mkdir: /builds/slave/test/build/tests 21:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:40 INFO - trying https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:41:40 INFO - Downloading https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 21:41:42 INFO - Downloaded 1199 bytes. 21:41:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:41:42 INFO - Using the following test package requirements: 21:41:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:41:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:41:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:41:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'jsshell-mac.zip'], 21:41:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:41:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:41:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:41:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:41:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:41:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:41:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:41:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:41:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:41:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:41:42 INFO - Fetch https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:41:42 INFO - Expected file size: 16294464 21:41:42 INFO - Obtained file size: 16294464 21:41:49 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:41:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:41:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:41:49 INFO - Fetch https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:41:50 INFO - Expected file size: 16294464 21:41:50 INFO - Obtained file size: 16294464 21:41:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:41:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:41:56 INFO - Fetch https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:42:02 INFO - Expected file size: 14409776 21:42:02 INFO - Obtained file size: 14409776 21:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:02 INFO - trying https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:42:02 INFO - Downloading https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:42:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 21:42:04 INFO - Downloaded 69697794 bytes. 21:42:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:42:04 INFO - mkdir: /builds/slave/test/properties 21:42:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:42:04 INFO - Writing to file /builds/slave/test/properties/build_url 21:42:04 INFO - Contents: 21:42:04 INFO - build_url:https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:42:04 INFO - Running post-action listener: _resource_record_post_action 21:42:04 INFO - Running post-action listener: set_extra_try_arguments 21:42:04 INFO - [mozharness: 2016-12-27 05:42:04.984311Z] Finished download-and-extract step (success) 21:42:04 INFO - [mozharness: 2016-12-27 05:42:04.984443Z] Running populate-webroot step. 21:42:04 INFO - Running pre-action listener: _resource_record_pre_action 21:42:04 INFO - Running main action method: populate_webroot 21:42:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:42:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:42:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:42:04 INFO - '--tppagecycles', 21:42:04 INFO - '7'], 21:42:04 INFO - 'tests': ['tsvgm']}}, 21:42:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:42:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:42:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:42:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:42:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:42:04 INFO - 'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:42:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:42:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:42:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:42:04 INFO - 'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['damp', 'tps']}, 21:42:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:42:04 INFO - 'tests': ['damp', 'tps']}, 21:42:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['dromaeo_dom']}, 21:42:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:42:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['basic_compositor_video']}, 21:42:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:42:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['a11yr', 21:42:04 INFO - 'ts_paint', 21:42:04 INFO - 'tpaint', 21:42:04 INFO - 'sessionrestore', 21:42:04 INFO - 'sessionrestore_no_auto_restore', 21:42:04 INFO - 'tabpaint']}, 21:42:04 INFO - 'other-e10s': {'tests': ['a11yr', 21:42:04 INFO - 'ts_paint', 21:42:04 INFO - 'tpaint', 21:42:04 INFO - 'sessionrestore', 21:42:04 INFO - 'sessionrestore_no_auto_restore', 21:42:04 INFO - 'tabpaint']}, 21:42:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:42:04 INFO - 'ts_paint', 21:42:04 INFO - 'tpaint', 21:42:04 INFO - 'sessionrestore', 21:42:04 INFO - 'sessionrestore_no_auto_restore', 21:42:04 INFO - 'tabpaint']}, 21:42:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:42:04 INFO - 'ts_paint', 21:42:04 INFO - 'tpaint', 21:42:04 INFO - 'sessionrestore', 21:42:04 INFO - 'sessionrestore_no_auto_restore', 21:42:04 INFO - 'tabpaint']}, 21:42:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['a11yr', 21:42:04 INFO - 'ts_paint', 21:42:04 INFO - 'tpaint', 21:42:04 INFO - 'sessionrestore', 21:42:04 INFO - 'sessionrestore_no_auto_restore', 21:42:04 INFO - 'tabpaint']}, 21:42:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:42:04 INFO - 'tests': ['a11yr', 21:42:04 INFO - 'ts_paint', 21:42:04 INFO - 'tpaint', 21:42:04 INFO - 'sessionrestore', 21:42:04 INFO - 'sessionrestore_no_auto_restore', 21:42:05 INFO - 'tabpaint']}, 21:42:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:42:05 INFO - 'tests': ['tsvgx', 21:42:05 INFO - 'tsvgr_opacity', 21:42:05 INFO - 'tart', 21:42:05 INFO - 'tscrollx', 21:42:05 INFO - 'cart', 21:42:05 INFO - 'tsvg_static']}, 21:42:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:42:05 INFO - 'tsvgr_opacity', 21:42:05 INFO - 'tart', 21:42:05 INFO - 'tscrollx', 21:42:05 INFO - 'cart', 21:42:05 INFO - 'tsvg_static']}, 21:42:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:42:05 INFO - 'talos_options': ['--disable-e10s'], 21:42:05 INFO - 'tests': ['tp5o']}, 21:42:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:42:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:42:05 INFO - 'talos_options': ['--disable-e10s', 21:42:05 INFO - '--xperf_path', 21:42:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:42:05 INFO - 'tests': ['tp5n']}, 21:42:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:42:05 INFO - 'talos_options': ['--xperf_path', 21:42:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:42:05 INFO - 'tests': ['tp5n']}}, 21:42:05 INFO - 'talos.zip': {'path': '', 21:42:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:42:05 INFO - Running post-action listener: _resource_record_post_action 21:42:05 INFO - [mozharness: 2016-12-27 05:42:05.003452Z] Finished populate-webroot step (success) 21:42:05 INFO - [mozharness: 2016-12-27 05:42:05.003564Z] Running create-virtualenv step. 21:42:05 INFO - Running pre-action listener: _resource_record_pre_action 21:42:05 INFO - Running main action method: create_virtualenv 21:42:05 INFO - Creating virtualenv /builds/slave/test/build/venv 21:42:05 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 21:42:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:42:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:42:05 INFO - Using real prefix '/tools/python27' 21:42:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:42:05 INFO - Installing distribute.............................................................................................................................................................................................done. 21:42:08 INFO - Installing pip.................done. 21:42:08 INFO - Return code: 0 21:42:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:42:08 INFO - Reading from file tmpfile_stdout 21:42:08 INFO - Output received: 21:42:08 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) 21:42:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:42: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')]} 21:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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')]} 21:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:08 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:08 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 21:42:08 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 21:42:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:08 INFO - 'HOME': '/Users/cltbld', 21:42:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:08 INFO - 'LOGNAME': 'cltbld', 21:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:08 INFO - 'MOZ_NO_REMOTE': '1', 21:42:08 INFO - 'NO_EM_RESTART': '1', 21:42:08 INFO - 'PAGER': '/bin/cat', 21:42:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:08 INFO - 'PWD': '/builds/slave/test', 21:42:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:08 INFO - 'SHELL': '/bin/bash', 21:42:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:08 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:08 INFO - 'USER': 'cltbld', 21:42:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:08 INFO - 'XPC_FLAGS': '0x0', 21:42:08 INFO - 'XPC_SERVICE_NAME': '0', 21:42:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:09 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 21:42:09 INFO - Cleaning up... 21:42:09 INFO - Return code: 0 21:42:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:09 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:09 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 21:42:09 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 21:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:09 INFO - 'HOME': '/Users/cltbld', 21:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:09 INFO - 'LOGNAME': 'cltbld', 21:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:09 INFO - 'MOZ_NO_REMOTE': '1', 21:42:09 INFO - 'NO_EM_RESTART': '1', 21:42:09 INFO - 'PAGER': '/bin/cat', 21:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:09 INFO - 'PWD': '/builds/slave/test', 21:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:09 INFO - 'SHELL': '/bin/bash', 21:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:09 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:09 INFO - 'USER': 'cltbld', 21:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:09 INFO - 'XPC_FLAGS': '0x0', 21:42:09 INFO - 'XPC_SERVICE_NAME': '0', 21:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:09 INFO - Downloading/unpacking psutil>=3.1.1 21:42:09 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 21:42:09 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 21:42:09 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 21:42:09 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 21:42:09 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 21:42:09 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 21:42:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:42:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:42:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:42:13 INFO - Installing collected packages: psutil 21:42:13 INFO - Running setup.py install for psutil 21:42:13 INFO - building 'psutil._psutil_osx' extension 21:42:13 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 21:42:13 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 21:42:13 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 21:42:14 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 21:42:14 INFO - building 'psutil._psutil_posix' extension 21:42:14 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 21:42:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:42:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:42:14 INFO - ^ 21:42:14 INFO - 1 warning generated. 21:42:14 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 21:42:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:42:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:42:14 INFO - Successfully installed psutil 21:42:14 INFO - Cleaning up... 21:42:14 INFO - Return code: 0 21:42:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:42:14 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')]} 21:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:14 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')]} 21:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:14 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:14 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 21:42:14 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 21:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:14 INFO - 'HOME': '/Users/cltbld', 21:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:14 INFO - 'LOGNAME': 'cltbld', 21:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:14 INFO - 'MOZ_NO_REMOTE': '1', 21:42:14 INFO - 'NO_EM_RESTART': '1', 21:42:14 INFO - 'PAGER': '/bin/cat', 21:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:14 INFO - 'PWD': '/builds/slave/test', 21:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:14 INFO - 'SHELL': '/bin/bash', 21:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:14 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:14 INFO - 'USER': 'cltbld', 21:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:14 INFO - 'XPC_FLAGS': '0x0', 21:42:14 INFO - 'XPC_SERVICE_NAME': '0', 21:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:42:14 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 21:42:14 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 21:42:14 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 21:42:14 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 21:42:14 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 21:42:14 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 21:42:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:42:17 INFO - Installing collected packages: mozsystemmonitor 21:42:17 INFO - Running setup.py install for mozsystemmonitor 21:42:17 INFO - Successfully installed mozsystemmonitor 21:42:17 INFO - Cleaning up... 21:42:17 INFO - Return code: 0 21:42:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:42:17 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')]} 21:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:17 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')]} 21:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:17 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:17 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 21:42:17 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 21:42:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:17 INFO - 'HOME': '/Users/cltbld', 21:42:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:17 INFO - 'LOGNAME': 'cltbld', 21:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:17 INFO - 'MOZ_NO_REMOTE': '1', 21:42:17 INFO - 'NO_EM_RESTART': '1', 21:42:17 INFO - 'PAGER': '/bin/cat', 21:42:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:17 INFO - 'PWD': '/builds/slave/test', 21:42:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:17 INFO - 'SHELL': '/bin/bash', 21:42:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:17 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:17 INFO - 'USER': 'cltbld', 21:42:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:17 INFO - 'XPC_FLAGS': '0x0', 21:42:17 INFO - 'XPC_SERVICE_NAME': '0', 21:42:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:17 INFO - Downloading/unpacking jsonschema==2.5.1 21:42:17 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 21:42:17 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 21:42:17 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 21:42:17 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 21:42:17 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 21:42:17 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 21:42:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:42:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:42:20 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 21:42:20 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 21:42:20 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 21:42:20 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 21:42:20 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 21:42:20 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 21:42:20 INFO - Downloading functools32-3.2.3-2.tar.gz 21:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:42:21 INFO - warning: no files found matching '*.txt' 21:42:21 INFO - no previously-included directories found matching 'build' 21:42:21 INFO - no previously-included directories found matching 'dist' 21:42:21 INFO - no previously-included directories found matching '.git*' 21:42:21 INFO - Installing collected packages: jsonschema, functools32 21:42:21 INFO - Running setup.py install for functools32 21:42:21 INFO - warning: no files found matching '*.txt' 21:42:21 INFO - no previously-included directories found matching 'build' 21:42:21 INFO - no previously-included directories found matching 'dist' 21:42:21 INFO - no previously-included directories found matching '.git*' 21:42:21 INFO - Successfully installed jsonschema functools32 21:42:21 INFO - Cleaning up... 21:42:21 INFO - Return code: 0 21:42:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:42: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')]} 21:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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')]} 21:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:21 INFO - 'HOME': '/Users/cltbld', 21:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:21 INFO - 'LOGNAME': 'cltbld', 21:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:21 INFO - 'MOZ_NO_REMOTE': '1', 21:42:21 INFO - 'NO_EM_RESTART': '1', 21:42:21 INFO - 'PAGER': '/bin/cat', 21:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:21 INFO - 'PWD': '/builds/slave/test', 21:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:21 INFO - 'SHELL': '/bin/bash', 21:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:21 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:21 INFO - 'USER': 'cltbld', 21:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:21 INFO - 'XPC_FLAGS': '0x0', 21:42:21 INFO - 'XPC_SERVICE_NAME': '0', 21:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:42:21 INFO - Cleaning up... 21:42:21 INFO - Return code: 0 21:42:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:42: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')]} 21:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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')]} 21:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:21 INFO - 'HOME': '/Users/cltbld', 21:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:21 INFO - 'LOGNAME': 'cltbld', 21:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:21 INFO - 'MOZ_NO_REMOTE': '1', 21:42:21 INFO - 'NO_EM_RESTART': '1', 21:42:21 INFO - 'PAGER': '/bin/cat', 21:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:21 INFO - 'PWD': '/builds/slave/test', 21:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:21 INFO - 'SHELL': '/bin/bash', 21:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:21 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:21 INFO - 'USER': 'cltbld', 21:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:21 INFO - 'XPC_FLAGS': '0x0', 21:42:21 INFO - 'XPC_SERVICE_NAME': '0', 21:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:21 INFO - Downloading/unpacking blobuploader==1.2.4 21:42:21 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 21:42:21 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 21:42:21 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 21:42:21 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 21:42:21 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 21:42:21 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 21:42:24 INFO - Downloading blobuploader-1.2.4.tar.gz 21:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:42:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:42:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:42:25 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 21:42:25 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 21:42:25 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 21:42:25 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 21:42:25 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 21:42:25 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 21:42:25 INFO - Downloading docopt-0.6.1.tar.gz 21:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:42:25 INFO - Installing collected packages: blobuploader, requests, docopt 21:42:25 INFO - Running setup.py install for blobuploader 21:42:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:42:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:42:25 INFO - Running setup.py install for requests 21:42:26 INFO - Running setup.py install for docopt 21:42:26 INFO - Successfully installed blobuploader requests docopt 21:42:26 INFO - Cleaning up... 21:42:26 INFO - Return code: 0 21:42:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:42: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')]} 21:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42: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')]} 21:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:26 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:26 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 21:42:26 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 21:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:26 INFO - 'HOME': '/Users/cltbld', 21:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:26 INFO - 'LOGNAME': 'cltbld', 21:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:26 INFO - 'MOZ_NO_REMOTE': '1', 21:42:26 INFO - 'NO_EM_RESTART': '1', 21:42:26 INFO - 'PAGER': '/bin/cat', 21:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:26 INFO - 'PWD': '/builds/slave/test', 21:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:26 INFO - 'SHELL': '/bin/bash', 21:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:26 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:26 INFO - 'USER': 'cltbld', 21:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:26 INFO - 'XPC_FLAGS': '0x0', 21:42:26 INFO - 'XPC_SERVICE_NAME': '0', 21:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:42:26 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-NQ6h8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-ycuUp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-BauhJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-2YIEYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-MRrAd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-EX2mph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-fax4Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-QUY_C3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-JqAhMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-QL5PlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:42:27 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-dI2nFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:42:28 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-0U0mwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:42:28 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-If94tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:42:28 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-3pIDpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:28 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-oJzmnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:42:28 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-lPaCvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:42:28 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-iz1kWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:42:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:42:28 INFO - Running setup.py install for manifestparser 21:42:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:42:28 INFO - Running setup.py install for mozcrash 21:42:28 INFO - Running setup.py install for mozdebug 21:42:29 INFO - Running setup.py install for mozdevice 21:42:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Running setup.py install for mozfile 21:42:29 INFO - Running setup.py install for mozhttpd 21:42:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Running setup.py install for mozinfo 21:42:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Running setup.py install for mozInstall 21:42:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:42:29 INFO - Running setup.py install for mozleak 21:42:30 INFO - Running setup.py install for mozlog 21:42:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:42:30 INFO - Running setup.py install for moznetwork 21:42:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:42:30 INFO - Running setup.py install for mozprocess 21:42:30 INFO - Running setup.py install for mozprofile 21:42:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:42:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:42:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:42:30 INFO - Running setup.py install for mozrunner 21:42:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:42:30 INFO - Running setup.py install for mozscreenshot 21:42:30 INFO - Running setup.py install for moztest 21:42:31 INFO - Running setup.py install for mozversion 21:42:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:42:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:42:31 INFO - Cleaning up... 21:42:31 INFO - Return code: 0 21:42:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:31 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:31 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 21:42:31 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 21:42:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:31 INFO - 'HOME': '/Users/cltbld', 21:42:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:31 INFO - 'LOGNAME': 'cltbld', 21:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:31 INFO - 'MOZ_NO_REMOTE': '1', 21:42:31 INFO - 'NO_EM_RESTART': '1', 21:42:31 INFO - 'PAGER': '/bin/cat', 21:42:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:31 INFO - 'PWD': '/builds/slave/test', 21:42:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:31 INFO - 'SHELL': '/bin/bash', 21:42:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:31 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:31 INFO - 'USER': 'cltbld', 21:42:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:31 INFO - 'XPC_FLAGS': '0x0', 21:42:31 INFO - 'XPC_SERVICE_NAME': '0', 21:42:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:42:31 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-zN3ntg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:42:31 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)) 21:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:42:31 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-Zbdg49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:42:31 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)) 21:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:42:31 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-UFfFaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:42:31 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)) 21:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:42:31 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-AwRupX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:42:31 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)) 21:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-7NKHGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-BLBT5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-zIoELc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-gkzCIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-mXQcfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-QCg0iV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-7U2AqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-oTTsXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:42:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-EiE5cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:42:32 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)) 21:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:42:32 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-y8vap9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:42:33 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)) 21:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:33 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-z4H3Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:42:33 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)) 21:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:42:33 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-ovqW68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 21:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:42:33 INFO - Running setup.py (path:/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/pip-F1URAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:42:33 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)) 21:42:33 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)) 21:42:33 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)) 21:42:33 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)) 21:42:33 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)) 21:42:33 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)) 21:42:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:42: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 21:42: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 21:42: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 21:42: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 21:42: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 21:42: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 21:42:34 INFO - Downloading blessings-1.6.tar.gz 21:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:42:34 INFO - Installing collected packages: blessings 21:42:34 INFO - Running setup.py install for blessings 21:42:34 INFO - Successfully installed blessings 21:42:34 INFO - Cleaning up... 21:42:35 INFO - Return code: 0 21:42:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:42:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:42:35 INFO - Reading from file tmpfile_stdout 21:42:35 INFO - Current package versions: 21:42:35 INFO - blessings == 1.6 21:42:35 INFO - blobuploader == 1.2.4 21:42:35 INFO - docopt == 0.6.1 21:42:35 INFO - functools32 == 3.2.3-2 21:42:35 INFO - jsonschema == 2.5.1 21:42:35 INFO - manifestparser == 1.1 21:42:35 INFO - mozInstall == 1.12 21:42:35 INFO - mozcrash == 1.0 21:42:35 INFO - mozdebug == 0.1 21:42:35 INFO - mozdevice == 0.49 21:42:35 INFO - mozfile == 1.2 21:42:35 INFO - mozhttpd == 0.7 21:42:35 INFO - mozinfo == 0.9 21:42:35 INFO - mozleak == 0.1 21:42:35 INFO - mozlog == 3.4 21:42:35 INFO - moznetwork == 0.27 21:42:35 INFO - mozprocess == 0.23 21:42:35 INFO - mozprofile == 0.28 21:42:35 INFO - mozrunner == 6.13 21:42:35 INFO - mozscreenshot == 0.1 21:42:35 INFO - mozsystemmonitor == 0.3 21:42:35 INFO - moztest == 0.7 21:42:35 INFO - mozversion == 1.4 21:42:35 INFO - psutil == 3.1.1 21:42:35 INFO - requests == 1.2.3 21:42:35 INFO - wsgiref == 0.1.2 21:42:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:35 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 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:35 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 21:42:35 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 21:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:35 INFO - 'HOME': '/Users/cltbld', 21:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:35 INFO - 'LOGNAME': 'cltbld', 21:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:35 INFO - 'MOZ_NO_REMOTE': '1', 21:42:35 INFO - 'NO_EM_RESTART': '1', 21:42:35 INFO - 'PAGER': '/bin/cat', 21:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:35 INFO - 'PWD': '/builds/slave/test', 21:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:35 INFO - 'SHELL': '/bin/bash', 21:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:35 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:35 INFO - 'USER': 'cltbld', 21:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:35 INFO - 'XPC_FLAGS': '0x0', 21:42:35 INFO - 'XPC_SERVICE_NAME': '0', 21:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 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)) 21:42:35 INFO - Cleaning up... 21:42:35 INFO - Return code: 0 21:42:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:42:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae17b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae1a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd579474eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, '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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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 21:42:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:35 INFO - 'HOME': '/Users/cltbld', 21:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:35 INFO - 'LOGNAME': 'cltbld', 21:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:35 INFO - 'MOZ_NO_REMOTE': '1', 21:42:35 INFO - 'NO_EM_RESTART': '1', 21:42:35 INFO - 'PAGER': '/bin/cat', 21:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:35 INFO - 'PWD': '/builds/slave/test', 21:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:35 INFO - 'SHELL': '/bin/bash', 21:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:35 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:35 INFO - 'USER': 'cltbld', 21:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:35 INFO - 'XPC_FLAGS': '0x0', 21:42:35 INFO - 'XPC_SERVICE_NAME': '0', 21:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:42:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:42:35 INFO - Cleaning up... 21:42:35 INFO - Return code: 0 21:42:35 INFO - Running post-action listener: _resource_record_post_action 21:42:35 INFO - Running post-action listener: _start_resource_monitoring 21:42:35 INFO - Starting resource monitoring. 21:42:35 INFO - [mozharness: 2016-12-27 05:42:35.961048Z] Finished create-virtualenv step (success) 21:42:35 INFO - [mozharness: 2016-12-27 05:42:35.961999Z] Running install step. 21:42:35 INFO - Running pre-action listener: _resource_record_pre_action 21:42:35 INFO - Running main action method: install 21:42:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:42:36 INFO - Reading from file tmpfile_stdout 21:42:36 INFO - Detecting whether we're running mozinstall >=1.0... 21:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:42:36 INFO - Reading from file tmpfile_stdout 21:42:36 INFO - Output received: 21:42:36 INFO - Usage: mozinstall [options] installer 21:42:36 INFO - Options: 21:42:36 INFO - -h, --help show this help message and exit 21:42:36 INFO - -d DEST, --destination=DEST 21:42:36 INFO - Directory to install application into. [default: 21:42:36 INFO - "/builds/slave/test"] 21:42:36 INFO - --app=APP Application being installed. [default: firefox] 21:42:36 INFO - mkdir: /builds/slave/test/build/application 21:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:42:57 INFO - Reading from file tmpfile_stdout 21:42:57 INFO - Output received: 21:42:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:42:57 INFO - Running post-action listener: _resource_record_post_action 21:42:57 INFO - [mozharness: 2016-12-27 05:42:57.998885Z] Finished install step (success) 21:42:57 INFO - [mozharness: 2016-12-27 05:42:57.999032Z] Running run-tests step. 21:42:57 INFO - Running pre-action listener: _resource_record_pre_action 21:42:57 INFO - Running main action method: run_tests 21:42:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:42:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:42:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:42:58 INFO - 2016-12-26 21:42:58.013 screenresolution[1640:8605] starting screenresolution argv=screenresolution get 21:42:58 INFO - 2016-12-26 21:42:58.016 screenresolution[1640:8605] Display 0: 1600x1200x32@60 21:42:58 INFO - 2016-12-26 21:42:58.021 screenresolution[1641:8608] starting screenresolution argv=screenresolution list 21:42:58 INFO - Available Modes on Display 0 21:42:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:42:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:42:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:42:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:42:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:42:58 INFO - Intel Iris: 21:42:58 INFO - Chipset Model: Intel Iris 21:42:58 INFO - Type: GPU 21:42:58 INFO - Bus: Built-In 21:42:58 INFO - VRAM (Dynamic, Max): 1536 MB 21:42:58 INFO - Vendor: Intel (0x8086) 21:42:58 INFO - Device ID: 0x0a2e 21:42:58 INFO - Revision ID: 0x0009 21:42:58 INFO - Displays: 21:42:58 INFO - Display: 21:42:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:42:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:42:58 INFO - Display Serial Number: 1600x1200 60 21:42:58 INFO - Main Display: Yes 21:42:58 INFO - Mirror: Off 21:42:58 INFO - Online: Yes 21:42:58 INFO - Rotation: Supported 21:42:58 INFO - Return code: 0 21:42:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:42:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:42:58 INFO - Python 2.7.3 21:42:58 INFO - Return code: 0 21:42:58 INFO - grabbing minidump binary from tooltool 21:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:42:58 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 0x10abf5d30>, '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 0x10ab1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abeabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:42:58 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 21:42:58 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 21:42:58 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 21:42:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:42:58 INFO - Return code: 0 21:42:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:42:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:42:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:42:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:42:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:42:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0202', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:42:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0202 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bZqdHAvo6e/Render', 21:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:42:58 INFO - 'HOME': '/Users/cltbld', 21:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:42:58 INFO - 'LOGNAME': 'cltbld', 21:42:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:42:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:42:58 INFO - 'MOZ_NO_REMOTE': '1', 21:42:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:42:58 INFO - 'NO_EM_RESTART': '1', 21:42:58 INFO - 'PAGER': '/bin/cat', 21:42:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:42:58 INFO - 'PWD': '/builds/slave/test', 21:42:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:42:58 INFO - 'SHELL': '/bin/bash', 21:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0O3ak5RX1B/Listeners', 21:42:58 INFO - 'TMPDIR': '/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/', 21:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:42:58 INFO - 'USER': 'cltbld', 21:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:42:58 INFO - 'XPC_FLAGS': '0x0', 21:42:58 INFO - 'XPC_SERVICE_NAME': '0', 21:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:42:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0202', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:42:58 INFO - mozversion application_buildid: 20161226205936 21:42:58 INFO - mozversion application_changeset: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a 21:42:58 INFO - mozversion application_display_name: Nightly 21:42:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:42:58 INFO - mozversion application_name: Firefox 21:42:58 INFO - mozversion application_remotingname: firefox 21:42:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:42:58 INFO - mozversion application_vendor: Mozilla 21:42:58 INFO - mozversion application_version: 53.0a1 21:42:58 INFO - mozversion platform_buildid: 20161226205936 21:42:58 INFO - mozversion platform_changeset: 4a1f36ebd21cbe090cd5d0ce6458af3d883a868a 21:42:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:42:58 INFO - mozversion platform_version: 53.0a1 21:42:58 INFO - using testdate: 1482817378 21:42:58 INFO - actual date: 1482817378 21:42:58 INFO - starting webserver on 'localhost:49241' 21:42:58 INFO - SUITE-START | Running 2 tests 21:42:58 INFO - TEST-START | dromaeo_css 21:42:58 INFO - operating with platform_type : mac_ 21:42:58 INFO - Initialising browser for dromaeo_css test... 21:42:58 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmpN9cxC8/profile http://localhost:49241/getInfo.html) 21:42:59 INFO - PROCESS | 1651 | 1482817379240 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 21:43:01 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 21:43:01 INFO - PROCESS | 1651 | colorDepth:24 21:43:01 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 21:43:01 INFO - PROCESS | 1651 | __metrics 21:43:01 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:43:01 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:43:01 INFO - TEST-INFO | 1651: exit 0 21:43:01 INFO - Browser initialized. 21:43:01 INFO - Running cycle 1/1 for dromaeo_css test... 21:43:01 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmpN9cxC8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:43:13 INFO - PROCESS | 1654 | RSS: Main: 234602496 21:43:13 INFO - PROCESS | 1654 | 21:46:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 21:46:20 INFO - PROCESS | 1654 | RSS: Main: 420995072 21:46:20 INFO - PROCESS | 1654 | 21:48:02 INFO - PROCESS | 1654 | ************************* 21:48:02 INFO - PROCESS | 1654 | A coding exception was thrown and uncaught in a Task. 21:48:02 INFO - PROCESS | 1654 | 21:48:02 INFO - PROCESS | 1654 | Full message: ReferenceError: fetch is not defined 21:48:02 INFO - PROCESS | 1654 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:48:02 INFO - PROCESS | 1654 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:48:02 INFO - PROCESS | 1654 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:48:02 INFO - PROCESS | 1654 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:48:02 INFO - PROCESS | 1654 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:48:02 INFO - PROCESS | 1654 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:48:02 INFO - PROCESS | 1654 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:48:02 INFO - PROCESS | 1654 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:48:02 INFO - PROCESS | 1654 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:48:02 INFO - PROCESS | 1654 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:48:02 INFO - PROCESS | 1654 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:48:02 INFO - PROCESS | 1654 | 21:48:02 INFO - PROCESS | 1654 | ************************* 21:49:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 21:49:24 INFO - PROCESS | 1654 | RSS: Main: 367566848 21:49:24 INFO - PROCESS | 1654 | 21:52:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 21:52:27 INFO - PROCESS | 1654 | RSS: Main: 356147200 21:52:27 INFO - PROCESS | 1654 | 21:55:29 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 21:55:30 INFO - PROCESS | 1654 | RSS: Main: 330960896 21:55:30 INFO - PROCESS | 1654 | 21:58:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 21:58:34 INFO - PROCESS | 1654 | RSS: Main: 330235904 21:58:34 INFO - PROCESS | 1654 | 22:01:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 22:01:38 INFO - PROCESS | 1654 | RSS: Main: 411480064 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | __start_tp_report 22:01:38 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 22:01:38 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 22:01:38 INFO - PROCESS | 1654 | |i|pagename|runs| 22:01:38 INFO - PROCESS | 1654 | |0;dojo.html;2850.586664933585;3348.146222713208;3363.4282172030767;3388.0344101930923;3427.211741299501;5617.4382561743805;5667.0932650775785;5719.1421286807;5758;5764.740586673599;5651;5716.085426331788;5725.398833122525;5731.627444216123;5735.684537350444;5146.819861304855;5154.07226699194;5192.454792246816;5219.817306394277;5250.9474905250845;5287.391949925769;5288.233206185101;5291.656042357254;5294.894102117955;5296.443873393296;4394.384786129928;5281.102212623844;5282.498162674539;5287.127623942045;5289.259503669489;5207.6354655174155;5273.920891186635;5276.9208461873295;5283.55089817366;5288.127458969266;5065.442801291855;5134.255532947702;5257.947420525822;5261.1319132343115;5282.286891269668;5844.064949608085;5850.297964244318;5850.531957443437;5855.912161317584;5870.295564532241;6276.780312689034;6280.403361680633;6289.339619339949;6295.2760432550385;6323.715432805535;6128;6162.661053642048;6185.56701030928;6202.81391558254;6219.564630475868;4608.700434471749;5793.8551536211935;5804.071348584206;5875.618084824473;5892.145638882424;6263.843403914848;6264.686765661699;6269.686515674198;6274.1843560337775;6287.717052732638;5908.113782932594;5949.791757288474;5954.761809527657;6204.968975155095;6210.534209934182;6145.4161854623735;6159.6612186329285;6171.475424588871;6173.41352571505;6232.376762323732;5212.765425555859;6232.56372053961;6248.0003199487865;6254;6261.7182226799905;6215.751369945242;6245.673792770269;6267.655278959701;6279.183706118267;6294.276158241861;4825.806967721322;4862.246351815475;4876.000419913909;4921.163402221631;4964.081644895706;4378.408914796525;4419.624331931822;4430.667699922518;4441.733495990251;4473.7986790594505;5820.068788993741;5836.7957121501395;5840.094785308284;5845.824625261249;5860.765569377178;5234.26720259164;6140.62695141751;6276.403741644536;6318.526110541728;6322.968385158045;5704;5752.13717942312;5773.971130144407;5810.593258471867;5837.562182836305;5414.187871819258;5422.7017514036315;5438.864028399322;5446.046941785172;5477.20580515823;6313.494920406449;6317.778877739165;6331.176946997045;6341.239051313872;6345;6125.723854043132;6167.876642467036;6183.443490085914;6252;6285.182926219562;6186.659733714689;6193.6903154841175;6221.004639257697;6304.71628776697;6308.306086330592;6192.535559833121;6216.875662486816;6219.626822390671;6220.440160385587;6227.034809604518;5885.117232415172;6147.586055207416;6309.053641953744;6314.652694101685;6319.6208227506495;6044.093385992136;6119.265688117454;6127.264728232641;6135.662538560244;6136.846578835565;6080.2399700037495;6099.2985806632805;6104.641056839769;6107.492261292593;6126.387361263838;5940.940590594038;5998.310194327708;6003.549733770072;6016.699165041818;6038.396160383926;5934.881302374015;6138.815835524942;6159.014557670751;6162.722677479614;6167.383261673796;5789.044807606698;5873.911891321548;5888;6005.969970150121;6013.819585412444 22:01:38 INFO - PROCESS | 1654 | |1;ext.html;419.8278705730651;445.9710118842276;476.77468904914383;484.89817138400934;494.9062571716506;22258.66612000819;30848.69151308489;37418.064548386305;38649.80675096628;38932.61067389325;39598.0100497487;40025.199496010064;40034.79982600083;40140.19719605606;40283.79858100705;9207.4475531468;9423.387479813824;9424.198943089845;9430.245580353556;9435.056494350545;8486.19381158789;8664.39349245553;10938.945305273503;11185.944070279636;11207.047400970927;11187.44062796861;11238.606648767294;11238.943805280962;11276.985071343577;11329.773404531905;11219.270747401433;11272.492737826817;11290.774184516345;11319.20765546412;11321.716957076056;8372.958135209285;8560.101189375151;8570.057293697688;8673.956630216808;11121.666350009431;30853.920112795946;38785.224295514214;39322.80338598317;39511.60488395108;39579.80210098931;3966.1431635043577;3980.7810570418615;3982.681385489154;3991.940120898189;4102.343625020012;28740.114180589142;29729.25676858074;29838.70161298381;29870.40259194804;29972;3125.3280544683003;3244.740420766333;3263.9555342290478;3294.901152965415;3297.3240485700376;382615;451370;451729;454976;457643;54574.454255457735;54767.72616136894;55388.44611553913;55390.44609553934;55448.445515545136;58001.709991449774;58050.41949580535;58061.290806378536;58123.70938145282;58488.4151158483;14510.201938893251;15069.698606028038;15080.849191507945;15084.849151508564;15093.622659433602;11733.823992640117;11829.17195796286;11833.171677982458;11835.408229588485;11856.466459009365;11104.61133860311;13039;13400.262985535694;18859.40201191349;19974.90012549957;8430.114837941928;8457.194262581863;8515.062303505281;8526.360522960802;8544.871826922601;704.9189343225493;858.9613467393982;861.7027125641644;869.3106545135124;869.4392117084445;26590.6011409829;28761.71238287632;29327.560086598718;29448.26379340491;29489.85255073711;1025.2105878473533;1030.9278350515392;1071.5874388360537;1075.1936047964027;1109.2124591540096;12960.935195323964;14387.92806035984;14828.851711482963;15245.780337572967;15276.923615381851;635230;638366;638884;641010;643059;49827.75086124619;50299.74850125726;50441.49558504368;50468.73828154326;50553.747231264344;1333;1354.1265883505084;1378.4692893236177;1380.2132784312846;1385.1066062389702;24622.507549848546;25531.276713808005;26508;27459.450810984068;28260.15219543417;43150.78424607857;43809.78095109504;43830.34254486249;43858;43862;584815;587908;588309;588454;588587;24347.51304973926;24608.507829842943;24657.534246575917;24679;24681.259562213163;32991.01026969195;35484.46773298351;37424.62575374211;38857;40487.59512404956;32680.50979235266;32842.305003495836;33217.335653287286;33385.499217512246;33696;32849.39199248616;33203;33657.15857103592;34375.31249375048;34449.827750861084;32054;32724;32757.181070473427;32904.34191316112;32986;23530;27602.033928813198;29455;29629.40741185121;30160.698393016668;22146.22488212905;22269.665955011013;22575.984080716735;22610.66084008774;22684.432889177904 22:01:38 INFO - PROCESS | 1654 | |2;jquery.html;351.3535095424419;370.70528929501063;372.11993635053096;379.11287587046314;399.65030598226554;13963.650908727286;14533.345999430025;18210.635787284245;18404.72392914104;19710.014499275014;18915.905420472915;19349.129289181987;19414.417567472963;19681.917494537796;19682.22859942605;5238.214267859823;5621.578381621394;5684.602077854552;5695.515881150107;5699.515541179005;4024.4768180136543;4247.980484683669;5584.636998595099;6065.181200537915;6337.936620633781;6385.425311721944;6389.616623002611;6395.20059992501;6396.872062558746;6407.903881441782;6067.908981365283;6290.182276304074;6340.239171299474;6349.36506349366;6351.301356850743;4683.929741053853;5163.896722065575;5469.863253418656;5473.534749546293;5485.506304432581;23898.761012389827;23920.162794302116;24006.159784407635;24193.15323963653;24234;2706.912562031327;2722.101706436871;2725.809193356466;2730.30377253801;2746.217328061493;13964.301784910815;14481.855181448156;14788.556343309718;15202.46791362308;15221.391144354213;1776.9289228430976;1814.845738112262;1828.7988321284647;1831.6611426885936;1834.1012903677235;99192;102021;102587;103031;103577.48211258896;10157.2925882054;10206.540705668263;10272.691819245434;10473.31923424975;10661.84007239892;11760.235584686969;11960.222585531912;12384.81422778659;12405.689857753448;12665.936670316774;6472.385123413268;7825.765227043198;7877.803054923673;7915.84168316642;8073.919260807317;7829.843403131906;7834.804129896798;7841.882371764428;7984.281414672707;8027.959860200778;5352.464753524694;6129.417705317987;6284.340144284784;6294.748210071637;6307.400796924377;1740.6518696260546;1831.9175637096096;1858.2683455320657;1859.2748827957107;1859.3399343233227;1035.5650626736788;1041.8020576090519;1046.4924511611862;1050.415996505231;1056.7622284985816;20459.38621841347;20486.38540843777;20489.38531844047;20522.794772052388;20656.48358791042;1034.1158309645145;1045.5242864496636;1062.4475272858072;1062.4581463453694;1069.385103565453;8062.475939063942;10087;10491.944060273532;10730.302530335503;10938.398388088732;286693;326742;539308;551613;553511;80347;98106;98177;99014;100337.49831250797;960.351762560283;966.1256562810527;981.0679854138597;1031.4430207687915;1032.5766435761452;16948.94918914607;17823.910880445514;17834.643307134043;17878.374256900945;18251.908740456212;50499;54193;54423.18365224446;54981.72509137429;55397.7230113863;55827.720861395435;70328;71522;72180;73279;6892.724291028303;7008.684609192701;7063.117110361205;7749.651265692944;7828.2954534092205;10925.289856159325;12023.27860328383;12433.93783031079;12456.318397016286;12622.242665440102;10416.031431042116;10445.321054131457;10789.298695584763;10875.184361172785;10926.890731092588;9592.848858137068;9942.403455792386;10317.27779055459;10536.209784266057;10709;9885.357451765612;10977.780444390908;10980.560777569128;11153;11220.848286134182;6877.552959057645;7077.32765387308;7087.362137407658;7214.170370407471;7238.167610724834;9650.565724542272;10266.281360304707;10427.582896684047;10806.459677016275;10877.51051202682 22:01:38 INFO - PROCESS | 1654 | |3;mootools.html;1283.9871601283987;1373.6909195431028;1395.8115654386654;1395.839478459977;1397.3502321420538;8067.072286687026;8408.285295749862;8424.410291279613;8424.452410593307;8428.241458268754;6227.782027629034;7608.77173684789;7879.80300492488;8108.472949258294;8179.754607361774;2253.177590179585;2302.481941563152;2307.2962746362355;2307.411610039446;2316.1893337331967;2268.353519247015;2301.9309420717404;2307.4116100394376;2309.145616122029;2315.7221133463922;2018.7476565429322;2104.105755054096;2225.732912050556;2271.6478945763433;2291.014863608648;2289.633658614622;2293.415179129328;2293.8738369389675;2298.321995011476;2300.769923007703;2271.965920511194;2285.1202287119577;2294.7246330440453;2300.459392042869;2302.792748652629;8124.065732440786;8320.875186872201;8344.707935222237;8345.082040975487;8347.95826020866;4967.751612419364;5125.641205115643;5135.845924622267;5142.640015198937;5143.562797162245;3215.6141263048585;3222.96777032229;3264.60824701035;3457.2048428861503;3529.2588556403184;1174.1956759619688;1218.530865616744;1240.6340129661771;1257.9119461637688;1276.6872116331558;10522.736931576695;10671.466426678713;10712.196585256137;10722;10730.141588672888;3928.3518219493462;4383.956160438354;4399.956000440018;4651.906961860744;4652.464966528893;9263.768405789775;9342.439453632667;9356.345055846026;9364.391314564531;9370.969193388722;5035.269885866529;5117.795288188505;5132.769025393866;5141.897162056738;5147.408048074444;4130.876073717794;4408.294672852328;4647.09381670571;4674.976625116853;4681.906361872743;3705.9629403705617;3739.2147648993473;3789.7536660117003;3827.923441531154;3850.2299540092095;1245.2590708528412;1249.3565813606;1253.3232054690366;1261.1298204239047;1266.9809952850715;755.61463653536;769.8498792735475;887.5251740318898;892.9732108036769;936.948467834262;5395.055865223649;5593.580481463907;5743.9425605744245;5798.710064496759;6088.360722124113;862.4221771413169;874.6501399440275;967.3905439573165;971.2618410008445;992.1913640383065;3544.8936531904083;3583.390823559949;3650.872219472306;3870.07118291608;3875.3799392097694;9262.027487113755;9321.627134914663;9515.333926625208;9682.273829462818;9946.254030947708;9782.168515676247;9794.804103917882;9800.166985806287;9807.803843923082;9816.558254878548;7332.853342933112;7767.650455729506;7865.528068315805;7869.881951770728;7881.211878812188;5417.889332686711;5657.179708942263;5671.461211184931;5738.799142030009;5790.623609465371;9084.545772711206;9123.635054597742;9243.953780231055;9258.074192580689;9287.303452241244;9311.44131352121;9326.813463730552;9346.859797103185;9348.485833279236;9514.666986655433;6648.800535983928;6780.898286525608;6796.966015169893;7060.223375428802;7231;7763.844723105619;7895.64469598858;7965.322947549524;7982.84034319322;8002.399820013406;7052.894206587008;7126.5011449198055;7163.3552980232025;7221.963890180516;7301.817454563678;6949.861002780017;6984.161900571964;6999.0551275577145;7093.361597456254;7159.212486626363;6339.397757213057;6341.334159913143;6419;6421.8715625688155;6438.291787903233;4760.59534939534;4789.281607758864;4808.326834243138;4836.274558816206;4917.483664215349;4676.953230467651;4708.835190768306;4776.140294746979;4839.201531747222;4952.281919121635 22:01:38 INFO - PROCESS | 1654 | |4;prototype.html;331.99902294581824;349.0871371363883;359.46260340790525;366.4082506751597;368.76951905059354;4634.212183928732;4677.848189832673;5085.702581318779;5281.366236051669;5301.284326615911;4597.804570811588;4990.3762029746285;5085.10989093343;5169.457206993268;5293.444188360225;3623.0797677231108;3953.268645300614;4134.235166494193;4136.710430269883;4156.711419459977;3474.096734848932;4125.195586860564;4139.3583994480905;4141.088960428701;4161.396597493362;3570.7143428525787;3573.94639080414;4009.1981603679237;4111.465509483763;4125.401816736571;3424.4863270509372;4056.7971601419813;4099.344104943195;4130.256553820296;4133.1940271647045;3455.706264967481;3630.8003059831963;3635.94546081809;3646.9270614587826;3657.1771351445977;5127.66670166438;5128.230765385185;5161.470879269169;5171.3277273954145;5171.50870667286;3851.1334949636107;4070.430139780433;4574.644058309973;4779.972305954202;4821.493743156953;4990.326305948687;5464.480874316973;5622.859428514279;5647.491725744702;5669.8582535436535;3152.921176970594;3228.5480032795426;3695.9076023099637;4022.8937042313596;4039.9596004039877;4982.234859652891;5032.84901452957;5721.027425337703;5816.476517113438;5890.556813580614;3932.2528719543197;3966.8809935701975;3967.5238971323624;3972.1330974442544;3988.66096381805;4023.195360927826;4034.0318323602123;4040.2323558523785;4040.777757223322;4054.2904991626347;4380;4573.862784116415;4668.50980647034;4674.691086495786;4685.8594242172785;4499.437570303712;5619.623192317827;5634;5637.746301416364;5660.97169514158;1356.905016648825;1358.8165597644388;1368.931553422325;1370.4792178971927;1379.3861731529573;899.2311573604604;920.4799288402031;924.2190349154953;953.594722243044;966.3815158298695;4986.102501549756;5015.645775640556;5042.773075211624;5053.418856831438;5061.8734531637;5117.948820511821;5455.754491047913;5474.479924407175;5487.835364938978;5586.854694787558;4347.478462491747;4617.3304870793545;4859.562639362403;4970.950290497121;5045.419776725724;2421.6630951132242;2480.9331987245296;2521.571332873416;2547.668882972097;2793.9662324939904;4767.308740232646;5067.594592432671;5438.809641662523;5540.97491963988;5599.776008959678;4701.694389864648;4725.1880187369725;5006.749662516932;5040.420351659533;5609.298837645294;4907.460179380265;5102.795888164506;5248.8425347239645;5522.723863806794;5647.5199608032135;4535.9773201133785;5113.731493219135;5298.364196296469;5351.678899266057;5484.698341591091;4480.75355855418;4710.8351207707565;5262.710550919736;5329.73351332424;5376.73116344189;4862.343583616166;5499.670019798825;5640.769422932801;5645.745941432728;5695.401982791747;3882.3793939090438;3893.9221215576094;3895.435161901452;3903.7117751142623;3960.663343615825;5117.488251174853;5331.97334013343;5459.771551400903;5613.410591887793;5778.422157784188;5000.399872041057;5211.9478805211465;5544.695041772741;5653.180288858175;5666.518345940476;4744.454387745315;5184.148718205048;5184.714840683799;5583.994880921311;5606.327240730975;5214.9478505214465;5328.907573947254;5421.728913554385;5445.700486473282;5454.263674404064;4399.130369592846;5032.572231360376;5379.112446446293;5411.891762164813;5661.547076233974;4655.976720116378;5230.293910322058;5363.141897296414;5547.833564993057;5550.639208451437 22:01:38 INFO - PROCESS | 1654 | |5;yui.html;712.5048091576353;723.7720118162781;765.6784150656723;795.4392153531759;798.9426632412124;14438.20589867557;15293.84706152941;15321;15322.463713770007;15341.38634454622;14200.573982780506;14357.138571685706;14366.994310398277;14380.352884120213;14414.495492657732;686.6909890549248;708.3200127877218;835.6114406800837;858.8711693246016;871.1375738019378;867.7223288547667;871.5337294547419;872.5811610426999;879.5909901895617;880.8238352329528;928.5774972387582;929.6281487405024;929.8047410043899;930.8231436027133;931.7391130483476;726.9563826170447;922.2760133295398;924.8289066522715;925.8240934222544;930.7580029192391;540.6972095626422;540.7728753923361;543.1364131031639;548.4241546376289;665.2316574356626;14731.96876218665;15165.620859478491;15169;15173;15186.240687965557;13577.660558486019;13640.931795340959;13666;13669.87691815858;13840.653983650389;12585.433655485525;12608.621741347682;12623.242605443611;12770.74458510834;12867.292298923556;4267.850625228133;4530.252508336121;4569.428821397325;4589.128065667513;4590;109042;110544;113624;114633.85366146314;114750.85249147569;10720.571177152982;10953.780924381468;10993.945030274797;11013.889861101447;11349.432528373549;16503;21524;21832.89083554572;21924.561508769737;21983.890080549496;7544.735934242385;7586.886196707053;7628.313451789366;7661.961690191513;7835.451518393772;4074.30736774749;4142.5857414258335;4151.807948998929;4155.667546596325;4161.375793630979;3385.779924304912;3447.465642825366;3454.5681789776277;3481.686648201674;3484.4773284007097;672.4586707700348;699.3076853914588;701.2006312803409;711.3704371631144;711.9537230080028;617.7220250887119;661.1933441201726;676.7394553096995;677.4047119034602;698.2912343970874;7353;8191.972285270544;8773.561321933877;8932.8660070099;8987.19115279628;616.2820314333841;622.3135303328032;634.1597383466925;653.1639501438071;657.552864052449;3951.786999280355;4349.3041113421705;4357.869263922024;4372.715773474714;4425.336199570091;106723;108117.459412704;108672;110443;111461.44269278602;63419.68290158612;94524.16427507048;94844.52577737067;97608.0239197599;98056.50971745096;81081;81673.77489337664;82276;83273.58363208266;84815;7026.9648651756415;8058.3150432213915;8366.99596048478;8506.609663612327;8770.809213940493;50986;51761;52240.7387963073;52846.735766320926;54315.91368172693;70212.648936755;70310;71959;72561;73373.89939150811;11550.48022838991;11617.302961822317;11713.355765432641;11939.82090268664;12056.51773929033;12605.621831345074;12613;12624;12683.42924568415;13040.543580975;12077.51689932393;12164.756704865677;12234.938825305817;12260.203086799329;12277.386130693609;11804.527818887149;11934.4032798358;12069.75860482803;12100.939495302468;12310.630681079581;11884.34636095023;11951;12035.638930832089;12120.636380908587;12366.567170149001;9919.40483570988;10224.693259202237;10368.533415996148;10411.583536658449;10522;9766.706998790047;9813.460259685786;9814.165795907427;9818.45998470091;9847.409155450696 22:01:38 INFO - PROCESS | 1654 | __end_tp_report 22:01:38 INFO - PROCESS | 1654 | __start_cc_report 22:01:38 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1847 22:01:38 INFO - PROCESS | 1654 | __end_cc_report 22:01:38 INFO - PROCESS | 1654 | __startTimestamp1482818498003__endTimestamp 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | ------- Summary: start ------- 22:01:38 INFO - PROCESS | 1654 | Number of tests: 6 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5737.29 Median:5934.88 stddev:658.26 (11.1%) stddev-sans-first:620.10 22:01:38 INFO - PROCESS | 1654 | Values: 2850.6 3348.1 3363.4 3388.0 3427.2 5617.4 5667.1 5719.1 5758.0 5764.7 5651.0 5716.1 5725.4 5731.6 5735.7 5146.8 5154.1 5192.5 5219.8 5250.9 5287.4 5288.2 5291.7 5294.9 5296.4 4394.4 5281.1 5282.5 5287.1 5289.3 5207.6 5273.9 5276.9 5283.6 5288.1 5065.4 5134.3 5257.9 5261.1 5282.3 5844.1 5850.3 5850.5 5855.9 5870.3 6276.8 6280.4 6289.3 6295.3 6323.7 6128.0 6162.7 6185.6 6202.8 6219.6 4608.7 5793.9 5804.1 5875.6 5892.1 6263.8 6264.7 6269.7 6274.2 6287.7 5908.1 5949.8 5954.8 6205.0 6210.5 6145.4 6159.7 6171.5 6173.4 6232.4 5212.8 6232.6 6248.0 6254.0 6261.7 6215.8 6245.7 6267.7 6279.2 6294.3 4825.8 4862.2 4876.0 4921.2 4964.1 4378.4 4419.6 4430.7 4441.7 4473.8 5820.1 5836.8 5840.1 5845.8 5860.8 5234.3 6140.6 6276.4 6318.5 6323.0 5704.0 5752.1 5774.0 5810.6 5837.6 5414.2 5422.7 5438.9 5446.0 5477.2 6313.5 6317.8 6331.2 6341.2 6345.0 6125.7 6167.9 6183.4 6252.0 6285.2 6186.7 6193.7 6221.0 6304.7 6308.3 6192.5 6216.9 6219.6 6220.4 6227.0 5885.1 6147.6 6309.1 6314.7 6319.6 6044.1 6119.3 6127.3 6135.7 6136.8 6080.2 6099.3 6104.6 6107.5 6126.4 5940.9 5998.3 6003.5 6016.7 6038.4 5934.9 6138.8 6159.0 6162.7 6167.4 5789.0 5873.9 5888.0 6006.0 6013.8 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:66912.03 Median:33097.01 stddev:150647.55 (455.2%) stddev-sans-first:150987.43 22:01:38 INFO - PROCESS | 1654 | Values: 419.8 446.0 476.8 484.9 494.9 22258.7 30848.7 37418.1 38649.8 38932.6 39598.0 40025.2 40034.8 40140.2 40283.8 9207.4 9423.4 9424.2 9430.2 9435.1 8486.2 8664.4 10938.9 11185.9 11207.0 11187.4 11238.6 11238.9 11277.0 11329.8 11219.3 11272.5 11290.8 11319.2 11321.7 8373.0 8560.1 8570.1 8674.0 11121.7 30853.9 38785.2 39322.8 39511.6 39579.8 3966.1 3980.8 3982.7 3991.9 4102.3 28740.1 29729.3 29838.7 29870.4 29972.0 3125.3 3244.7 3264.0 3294.9 3297.3 382615.0 451370.0 451729.0 454976.0 457643.0 54574.5 54767.7 55388.4 55390.4 55448.4 58001.7 58050.4 58061.3 58123.7 58488.4 14510.2 15069.7 15080.8 15084.8 15093.6 11733.8 11829.2 11833.2 11835.4 11856.5 11104.6 13039.0 13400.3 18859.4 19974.9 8430.1 8457.2 8515.1 8526.4 8544.9 704.9 859.0 861.7 869.3 869.4 26590.6 28761.7 29327.6 29448.3 29489.9 1025.2 1030.9 1071.6 1075.2 1109.2 12960.9 14387.9 14828.9 15245.8 15276.9 635230.0 638366.0 638884.0 641010.0 643059.0 49827.8 50299.7 50441.5 50468.7 50553.7 1333.0 1354.1 1378.5 1380.2 1385.1 24622.5 25531.3 26508.0 27459.5 28260.2 43150.8 43809.8 43830.3 43858.0 43862.0 584815.0 587908.0 588309.0 588454.0 588587.0 24347.5 24608.5 24657.5 24679.0 24681.3 32991.0 35484.5 37424.6 38857.0 40487.6 32680.5 32842.3 33217.3 33385.5 33696.0 32849.4 33203.0 33657.2 34375.3 34449.8 32054.0 32724.0 32757.2 32904.3 32986.0 23530.0 27602.0 29455.0 29629.4 30160.7 22146.2 22269.7 22576.0 22610.7 22684.4 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:29064.95 Median:24213.58 stddev:78120.56 (322.6%) stddev-sans-first:78309.95 22:01:38 INFO - PROCESS | 1654 | Values: 351.4 370.7 372.1 379.1 399.7 13963.7 14533.3 18210.6 18404.7 19710.0 18915.9 19349.1 19414.4 19681.9 19682.2 5238.2 5621.6 5684.6 5695.5 5699.5 4024.5 4248.0 5584.6 6065.2 6337.9 6385.4 6389.6 6395.2 6396.9 6407.9 6067.9 6290.2 6340.2 6349.4 6351.3 4683.9 5163.9 5469.9 5473.5 5485.5 23898.8 23920.2 24006.2 24193.2 24234.0 2706.9 2722.1 2725.8 2730.3 2746.2 13964.3 14481.9 14788.6 15202.5 15221.4 1776.9 1814.8 1828.8 1831.7 1834.1 99192.0 102021.0 102587.0 103031.0 103577.5 10157.3 10206.5 10272.7 10473.3 10661.8 11760.2 11960.2 12384.8 12405.7 12665.9 6472.4 7825.8 7877.8 7915.8 8073.9 7829.8 7834.8 7841.9 7984.3 8028.0 5352.5 6129.4 6284.3 6294.7 6307.4 1740.7 1831.9 1858.3 1859.3 1859.3 1035.6 1041.8 1046.5 1050.4 1056.8 20459.4 20486.4 20489.4 20522.8 20656.5 1034.1 1045.5 1062.4 1062.5 1069.4 8062.5 10087.0 10491.9 10730.3 10938.4 286693.0 326742.0 539308.0 551613.0 553511.0 80347.0 98106.0 98177.0 99014.0 100337.5 960.4 966.1 981.1 1031.4 1032.6 16948.9 17823.9 17834.6 17878.4 18251.9 50499.0 54193.0 54423.2 54981.7 55397.7 55827.7 70328.0 71522.0 72180.0 73279.0 6892.7 7008.7 7063.1 7749.7 7828.3 10925.3 12023.3 12433.9 12456.3 12622.2 10416.0 10445.3 10789.3 10875.2 10926.9 9592.8 9942.4 10317.3 10536.2 10709.0 9885.4 10977.8 10980.6 11153.0 11220.8 6877.6 7077.3 7087.4 7214.2 7238.2 9650.6 10266.3 10427.6 10806.5 10877.5 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5369.44 Median:5406.47 stddev:2927.40 (54.1%) stddev-sans-first:2919.51 22:01:38 INFO - PROCESS | 1654 | Values: 1284.0 1373.7 1395.8 1395.8 1397.4 8067.1 8408.3 8424.4 8424.5 8428.2 6227.8 7608.8 7879.8 8108.5 8179.8 2253.2 2302.5 2307.3 2307.4 2316.2 2268.4 2301.9 2307.4 2309.1 2315.7 2018.7 2104.1 2225.7 2271.6 2291.0 2289.6 2293.4 2293.9 2298.3 2300.8 2272.0 2285.1 2294.7 2300.5 2302.8 8124.1 8320.9 8344.7 8345.1 8348.0 4967.8 5125.6 5135.8 5142.6 5143.6 3215.6 3223.0 3264.6 3457.2 3529.3 1174.2 1218.5 1240.6 1257.9 1276.7 10522.7 10671.5 10712.2 10722.0 10730.1 3928.4 4384.0 4400.0 4651.9 4652.5 9263.8 9342.4 9356.3 9364.4 9371.0 5035.3 5117.8 5132.8 5141.9 5147.4 4130.9 4408.3 4647.1 4675.0 4681.9 3706.0 3739.2 3789.8 3827.9 3850.2 1245.3 1249.4 1253.3 1261.1 1267.0 755.6 769.8 887.5 893.0 936.9 5395.1 5593.6 5743.9 5798.7 6088.4 862.4 874.7 967.4 971.3 992.2 3544.9 3583.4 3650.9 3870.1 3875.4 9262.0 9321.6 9515.3 9682.3 9946.3 9782.2 9794.8 9800.2 9807.8 9816.6 7332.9 7767.7 7865.5 7869.9 7881.2 5417.9 5657.2 5671.5 5738.8 5790.6 9084.5 9123.6 9244.0 9258.1 9287.3 9311.4 9326.8 9346.9 9348.5 9514.7 6648.8 6780.9 6797.0 7060.2 7231.0 7763.8 7895.6 7965.3 7982.8 8002.4 7052.9 7126.5 7163.4 7222.0 7301.8 6949.9 6984.2 6999.1 7093.4 7159.2 6339.4 6341.3 6419.0 6421.9 6438.3 4760.6 4789.3 4808.3 4836.3 4917.5 4677.0 4708.8 4776.1 4839.2 4952.3 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4422.27 Median:4988.21 stddev:1310.70 (26.3%) stddev-sans-first:1277.92 22:01:38 INFO - PROCESS | 1654 | Values: 332.0 349.1 359.5 366.4 368.8 4634.2 4677.8 5085.7 5281.4 5301.3 4597.8 4990.4 5085.1 5169.5 5293.4 3623.1 3953.3 4134.2 4136.7 4156.7 3474.1 4125.2 4139.4 4141.1 4161.4 3570.7 3573.9 4009.2 4111.5 4125.4 3424.5 4056.8 4099.3 4130.3 4133.2 3455.7 3630.8 3635.9 3646.9 3657.2 5127.7 5128.2 5161.5 5171.3 5171.5 3851.1 4070.4 4574.6 4780.0 4821.5 4990.3 5464.5 5622.9 5647.5 5669.9 3152.9 3228.5 3695.9 4022.9 4040.0 4982.2 5032.8 5721.0 5816.5 5890.6 3932.3 3966.9 3967.5 3972.1 3988.7 4023.2 4034.0 4040.2 4040.8 4054.3 4380.0 4573.9 4668.5 4674.7 4685.9 4499.4 5619.6 5634.0 5637.7 5661.0 1356.9 1358.8 1368.9 1370.5 1379.4 899.2 920.5 924.2 953.6 966.4 4986.1 5015.6 5042.8 5053.4 5061.9 5117.9 5455.8 5474.5 5487.8 5586.9 4347.5 4617.3 4859.6 4971.0 5045.4 2421.7 2480.9 2521.6 2547.7 2794.0 4767.3 5067.6 5438.8 5541.0 5599.8 4701.7 4725.2 5006.7 5040.4 5609.3 4907.5 5102.8 5248.8 5522.7 5647.5 4536.0 5113.7 5298.4 5351.7 5484.7 4480.8 4710.8 5262.7 5329.7 5376.7 4862.3 5499.7 5640.8 5645.7 5695.4 3882.4 3893.9 3895.4 3903.7 3960.7 5117.5 5332.0 5459.8 5613.4 5778.4 5000.4 5211.9 5544.7 5653.2 5666.5 4744.5 5184.1 5184.7 5584.0 5606.3 5214.9 5328.9 5421.7 5445.7 5454.3 4399.1 5032.6 5379.1 5411.9 5661.5 4656.0 5230.3 5363.1 5547.8 5550.6 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:20807.98 Median:4549.84 stddev:31132.42 (684.3%) stddev-sans-first:31183.19 22:01:38 INFO - PROCESS | 1654 | Values: 712.5 723.8 765.7 795.4 798.9 14438.2 15293.8 15321.0 15322.5 15341.4 14200.6 14357.1 14367.0 14380.4 14414.5 686.7 708.3 835.6 858.9 871.1 867.7 871.5 872.6 879.6 880.8 928.6 929.6 929.8 930.8 931.7 727.0 922.3 924.8 925.8 930.8 540.7 540.8 543.1 548.4 665.2 14732.0 15165.6 15169.0 15173.0 15186.2 13577.7 13640.9 13666.0 13669.9 13840.7 12585.4 12608.6 12623.2 12770.7 12867.3 4267.9 4530.3 4569.4 4589.1 4590.0 109042.0 110544.0 113624.0 114633.9 114750.9 10720.6 10953.8 10993.9 11013.9 11349.4 16503.0 21524.0 21832.9 21924.6 21983.9 7544.7 7586.9 7628.3 7662.0 7835.5 4074.3 4142.6 4151.8 4155.7 4161.4 3385.8 3447.5 3454.6 3481.7 3484.5 672.5 699.3 701.2 711.4 712.0 617.7 661.2 676.7 677.4 698.3 7353.0 8192.0 8773.6 8932.9 8987.2 616.3 622.3 634.2 653.2 657.6 3951.8 4349.3 4357.9 4372.7 4425.3 106723.0 108117.5 108672.0 110443.0 111461.4 63419.7 94524.2 94844.5 97608.0 98056.5 81081.0 81673.8 82276.0 83273.6 84815.0 7027.0 8058.3 8367.0 8506.6 8770.8 50986.0 51761.0 52240.7 52846.7 54315.9 70212.6 70310.0 71959.0 72561.0 73373.9 11550.5 11617.3 11713.4 11939.8 12056.5 12605.6 12613.0 12624.0 12683.4 13040.5 12077.5 12164.8 12234.9 12260.2 12277.4 11804.5 11934.4 12069.8 12100.9 12310.6 11884.3 11951.0 12035.6 12120.6 12366.6 9919.4 10224.7 10368.5 10411.6 10522.0 9766.7 9813.5 9814.2 9818.5 9847.4 22:01:38 INFO - PROCESS | 1654 | -------- Summary: end -------- 22:01:38 INFO - PROCESS | 1654 | 22:01:38 INFO - TEST-INFO | 1654: exit 0 22:01:38 INFO - TEST-OK | dromaeo_css | took 1120040ms 22:01:38 INFO - TEST-START | kraken 22:01:38 INFO - operating with platform_type : mac_ 22:01:38 INFO - Initialising browser for kraken test... 22:01:38 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmp0jO_ZJ/profile http://localhost:49241/getInfo.html) 22:01:39 INFO - PROCESS | 1659 | 1482818499133 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 22:01:40 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 22:01:40 INFO - PROCESS | 1659 | colorDepth:24 22:01:40 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 22:01:40 INFO - PROCESS | 1659 | __metrics 22:01:41 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:01:41 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:01:41 INFO - TEST-INFO | 1659: exit 0 22:01:41 INFO - Browser initialized. 22:01:41 INFO - Running cycle 1/1 for kraken test... 22:01:41 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/tmp0jO_ZJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:01:53 INFO - PROCESS | 1660 | RSS: Main: 233811968 22:01:53 INFO - PROCESS | 1660 | 22:01:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 22:01:57 INFO - PROCESS | 1660 | RSS: Main: 288337920 22:01:57 INFO - PROCESS | 1660 | 22:02:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 22:02:00 INFO - PROCESS | 1660 | RSS: Main: 288575488 22:02:00 INFO - PROCESS | 1660 | 22:02:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 22:02:04 INFO - PROCESS | 1660 | RSS: Main: 378044416 22:02:04 INFO - PROCESS | 1660 | 22:02:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 22:02:07 INFO - PROCESS | 1660 | RSS: Main: 279388160 22:02:07 INFO - PROCESS | 1660 | 22:02:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:02:09 INFO - PROCESS | 1660 | RSS: Main: 282992640 22:02:09 INFO - PROCESS | 1660 | 22:02:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 22:02:13 INFO - PROCESS | 1660 | RSS: Main: 324001792 22:02:13 INFO - PROCESS | 1660 | 22:02:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 22:02:16 INFO - PROCESS | 1660 | RSS: Main: 328163328 22:02:16 INFO - PROCESS | 1660 | 22:02:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 22:02:20 INFO - PROCESS | 1660 | RSS: Main: 326201344 22:02:20 INFO - PROCESS | 1660 | 22:02:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:02:22 INFO - PROCESS | 1660 | RSS: Main: 303149056 22:02:22 INFO - PROCESS | 1660 | 22:02:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:02:25 INFO - PROCESS | 1660 | RSS: Main: 306057216 22:02:25 INFO - PROCESS | 1660 | 22:02:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:02:27 INFO - PROCESS | 1660 | RSS: Main: 308203520 22:02:27 INFO - PROCESS | 1660 | 22:02:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:02:31 INFO - PROCESS | 1660 | RSS: Main: 323842048 22:02:31 INFO - PROCESS | 1660 | 22:02:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:02:35 INFO - PROCESS | 1660 | RSS: Main: 318345216 22:02:35 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 22:02:36 INFO - PROCESS | 1660 | RSS: Main: 362545152 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | __start_tp_report 22:02:36 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 22:02:36 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 22:02:36 INFO - PROCESS | 1660 | |i|pagename|runs| 22:02:36 INFO - PROCESS | 1660 | |0;ai-astar;113;113;112;113;113;127;165;167;118;113 22:02:36 INFO - PROCESS | 1660 | |1;audio-beat-detection;143;112;137;177;114;111;112;113;129;113 22:02:36 INFO - PROCESS | 1660 | |2;audio-dft;187;182;184;184;185;181;180;184;184;184 22:02:36 INFO - PROCESS | 1660 | |3;audio-fft;78;59;58;87;61;58;58;57;74;59 22:02:36 INFO - PROCESS | 1660 | |4;audio-oscillator;88;71;87;69;69;70;84;70;69;83 22:02:36 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;89;87;88;86;88;85;89;86;88;84 22:02:36 INFO - PROCESS | 1660 | |6;imaging-darkroom;93;100;137;93;106;147;123;93;93;93 22:02:36 INFO - PROCESS | 1660 | |7;imaging-desaturate;86;85;86;85;85;85;88;82;84;87 22:02:36 INFO - PROCESS | 1660 | |8;json-parse-financial;52;54;54;55;55;57;57;56;55;55 22:02:36 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;73;69;85;77;73;72;70;72;70;98 22:02:36 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;90;88;86;89;87;86;83;82;100;102 22:02:36 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;136;140;137;141;135;131;143;147;206;151 22:02:36 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;171;171;170;172;168;175;173;172;171;171 22:02:36 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;68;67;67;66;68;66;66;66;67;66 22:02:36 INFO - PROCESS | 1660 | __end_tp_report 22:02:36 INFO - PROCESS | 1660 | __start_cc_report 22:02:36 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1941 22:02:36 INFO - PROCESS | 1660 | __end_cc_report 22:02:36 INFO - PROCESS | 1660 | __startTimestamp1482818556978__endTimestamp 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | ------- Summary: start ------- 22:02:36 INFO - PROCESS | 1660 | Number of tests: 14 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:125.40 Median:115.50 stddev:21.87 (18.9%) stddev-sans-first:22.73 22:02:36 INFO - PROCESS | 1660 | Values: 113.0 113.0 112.0 113.0 113.0 127.0 165.0 167.0 118.0 113.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:121.50 stddev:21.39 (17.6%) stddev-sans-first:21.80 22:02:36 INFO - PROCESS | 1660 | Values: 143.0 112.0 137.0 177.0 114.0 111.0 112.0 113.0 129.0 113.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:2.01 (1.1%) stddev-sans-first:1.69 22:02:36 INFO - PROCESS | 1660 | Values: 187.0 182.0 184.0 184.0 185.0 181.0 180.0 184.0 184.0 184.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:64.90 Median:60.00 stddev:10.71 (17.9%) stddev-sans-first:10.26 22:02:36 INFO - PROCESS | 1660 | Values: 78.0 59.0 58.0 87.0 61.0 58.0 58.0 57.0 74.0 59.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.00 stddev:8.31 (10.8%) stddev-sans-first:7.60 22:02:36 INFO - PROCESS | 1660 | Values: 88.0 71.0 87.0 69.0 69.0 70.0 84.0 70.0 69.0 83.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.70 (1.9%) stddev-sans-first:1.64 22:02:36 INFO - PROCESS | 1660 | Values: 89.0 87.0 88.0 86.0 88.0 85.0 89.0 86.0 88.0 84.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:93.00 stddev:20.49 (22.0%) stddev-sans-first:21.02 22:02:36 INFO - PROCESS | 1660 | Values: 93.0 100.0 137.0 93.0 106.0 147.0 123.0 93.0 93.0 93.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.64 (1.9%) stddev-sans-first:1.72 22:02:36 INFO - PROCESS | 1660 | Values: 86.0 85.0 86.0 85.0 85.0 85.0 88.0 82.0 84.0 87.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.49 (2.7%) stddev-sans-first:1.12 22:02:36 INFO - PROCESS | 1660 | Values: 52.0 54.0 54.0 55.0 55.0 57.0 57.0 56.0 55.0 55.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:73.00 stddev:9.05 (12.4%) stddev-sans-first:9.54 22:02:36 INFO - PROCESS | 1660 | Values: 73.0 69.0 85.0 77.0 73.0 72.0 70.0 72.0 70.0 98.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:86.50 stddev:6.65 (7.7%) stddev-sans-first:7.05 22:02:36 INFO - PROCESS | 1660 | Values: 90.0 88.0 86.0 89.0 87.0 86.0 83.0 82.0 100.0 102.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:142.00 stddev:21.65 (15.2%) stddev-sans-first:22.61 22:02:36 INFO - PROCESS | 1660 | Values: 136.0 140.0 137.0 141.0 135.0 131.0 143.0 147.0 206.0 151.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:171.50 stddev:1.84 (1.1%) stddev-sans-first:1.94 22:02:36 INFO - PROCESS | 1660 | Values: 171.0 171.0 170.0 172.0 168.0 175.0 173.0 172.0 171.0 171.0 22:02:36 INFO - PROCESS | 1660 | 22:02:36 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.73 22:02:36 INFO - PROCESS | 1660 | Values: 68.0 67.0 67.0 66.0 68.0 66.0 66.0 66.0 67.0 66.0 22:02:36 INFO - PROCESS | 1660 | -------- Summary: end -------- 22:02:36 INFO - PROCESS | 1660 | 22:02:37 INFO - TEST-INFO | 1660: exit 0 22:02:37 INFO - TEST-OK | kraken | took 59204ms 22:02:37 INFO - SUITE-END | took 1179s 22:02:38 INFO - Completed test suite (00:19:39) 22:02:38 INFO - javascript benchmark 22:02:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2850.586664933585, 3348.146222713208, 3363.4282172030767, 3388.0344101930923, 3427.211741299501, 5617.4382561743805, 5667.0932650775785, 5719.1421286807, 5758.0, 5764.740586673599, 5651.0, 5716.085426331788, 5725.398833122525, 5731.627444216123, 5735.684537350444, 5146.819861304855, 5154.07226699194, 5192.454792246816, 5219.817306394277, 5250.9474905250845, 5287.391949925769, 5288.233206185101, 5291.656042357254, 5294.894102117955, 5296.443873393296, 4394.384786129928, 5281.102212623844, 5282.498162674539, 5287.127623942045, 5289.259503669489, 5207.6354655174155, 5273.920891186635, 5276.9208461873295, 5283.55089817366, 5288.127458969266, 5065.442801291855, 5134.255532947702, 5257.947420525822, 5261.1319132343115, 5282.286891269668, 5844.064949608085, 5850.297964244318, 5850.531957443437, 5855.912161317584, 5870.295564532241, 6276.780312689034, 6280.403361680633, 6289.339619339949, 6295.2760432550385, 6323.715432805535, 6128.0, 6162.661053642048, 6185.56701030928, 6202.81391558254, 6219.564630475868, 4608.700434471749, 5793.8551536211935, 5804.071348584206, 5875.618084824473, 5892.145638882424, 6263.843403914848, 6264.686765661699, 6269.686515674198, 6274.1843560337775, 6287.717052732638, 5908.113782932594, 5949.791757288474, 5954.761809527657, 6204.968975155095, 6210.534209934182, 6145.4161854623735, 6159.6612186329285, 6171.475424588871, 6173.41352571505, 6232.376762323732, 5212.765425555859, 6232.56372053961, 6248.0003199487865, 6254.0, 6261.7182226799905, 6215.751369945242, 6245.673792770269, 6267.655278959701, 6279.183706118267, 6294.276158241861, 4825.806967721322, 4862.246351815475, 4876.000419913909, 4921.163402221631, 4964.081644895706, 4378.408914796525, 4419.624331931822, 4430.667699922518, 4441.733495990251, 4473.7986790594505, 5820.068788993741, 5836.7957121501395, 5840.094785308284, 5845.824625261249, 5860.765569377178, 5234.26720259164, 6140.62695141751, 6276.403741644536, 6318.526110541728, 6322.968385158045, 5704.0, 5752.13717942312, 5773.971130144407, 5810.593258471867, 5837.562182836305, 5414.187871819258, 5422.7017514036315, 5438.864028399322, 5446.046941785172, 5477.20580515823, 6313.494920406449, 6317.778877739165, 6331.176946997045, 6341.239051313872, 6345.0, 6125.723854043132, 6167.876642467036, 6183.443490085914, 6252.0, 6285.182926219562, 6186.659733714689, 6193.6903154841175, 6221.004639257697, 6304.71628776697, 6308.306086330592, 6192.535559833121, 6216.875662486816, 6219.626822390671, 6220.440160385587, 6227.034809604518, 5885.117232415172, 6147.586055207416, 6309.053641953744, 6314.652694101685, 6319.6208227506495, 6044.093385992136, 6119.265688117454, 6127.264728232641, 6135.662538560244, 6136.846578835565, 6080.2399700037495, 6099.2985806632805, 6104.641056839769, 6107.492261292593, 6126.387361263838, 5940.940590594038, 5998.310194327708, 6003.549733770072, 6016.699165041818, 6038.396160383926, 5934.881302374015, 6138.815835524942, 6159.014557670751, 6162.722677479614, 6167.383261673796, 5789.044807606698, 5873.911891321548, 5888.0, 6005.969970150121, 6013.819585412444], "value": 5694.493343446697, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.8278705730651, 445.9710118842276, 476.77468904914383, 484.89817138400934, 494.9062571716506, 22258.66612000819, 30848.69151308489, 37418.064548386305, 38649.80675096628, 38932.61067389325, 39598.0100497487, 40025.199496010064, 40034.79982600083, 40140.19719605606, 40283.79858100705, 9207.4475531468, 9423.387479813824, 9424.198943089845, 9430.245580353556, 9435.056494350545, 8486.19381158789, 8664.39349245553, 10938.945305273503, 11185.944070279636, 11207.047400970927, 11187.44062796861, 11238.606648767294, 11238.943805280962, 11276.985071343577, 11329.773404531905, 11219.270747401433, 11272.492737826817, 11290.774184516345, 11319.20765546412, 11321.716957076056, 8372.958135209285, 8560.101189375151, 8570.057293697688, 8673.956630216808, 11121.666350009431, 30853.920112795946, 38785.224295514214, 39322.80338598317, 39511.60488395108, 39579.80210098931, 3966.1431635043577, 3980.7810570418615, 3982.681385489154, 3991.940120898189, 4102.343625020012, 28740.114180589142, 29729.25676858074, 29838.70161298381, 29870.40259194804, 29972.0, 3125.3280544683003, 3244.740420766333, 3263.9555342290478, 3294.901152965415, 3297.3240485700376, 382615.0, 451370.0, 451729.0, 454976.0, 457643.0, 54574.454255457735, 54767.72616136894, 55388.44611553913, 55390.44609553934, 55448.445515545136, 58001.709991449774, 58050.41949580535, 58061.290806378536, 58123.70938145282, 58488.4151158483, 14510.201938893251, 15069.698606028038, 15080.849191507945, 15084.849151508564, 15093.622659433602, 11733.823992640117, 11829.17195796286, 11833.171677982458, 11835.408229588485, 11856.466459009365, 11104.61133860311, 13039.0, 13400.262985535694, 18859.40201191349, 19974.90012549957, 8430.114837941928, 8457.194262581863, 8515.062303505281, 8526.360522960802, 8544.871826922601, 704.9189343225493, 858.9613467393982, 861.7027125641644, 869.3106545135124, 869.4392117084445, 26590.6011409829, 28761.71238287632, 29327.560086598718, 29448.26379340491, 29489.85255073711, 1025.2105878473533, 1030.9278350515392, 1071.5874388360537, 1075.1936047964027, 1109.2124591540096, 12960.935195323964, 14387.92806035984, 14828.851711482963, 15245.780337572967, 15276.923615381851, 635230.0, 638366.0, 638884.0, 641010.0, 643059.0, 49827.75086124619, 50299.74850125726, 50441.49558504368, 50468.73828154326, 50553.747231264344, 1333.0, 1354.1265883505084, 1378.4692893236177, 1380.2132784312846, 1385.1066062389702, 24622.507549848546, 25531.276713808005, 26508.0, 27459.450810984068, 28260.15219543417, 43150.78424607857, 43809.78095109504, 43830.34254486249, 43858.0, 43862.0, 584815.0, 587908.0, 588309.0, 588454.0, 588587.0, 24347.51304973926, 24608.507829842943, 24657.534246575917, 24679.0, 24681.259562213163, 32991.01026969195, 35484.46773298351, 37424.62575374211, 38857.0, 40487.59512404956, 32680.50979235266, 32842.305003495836, 33217.335653287286, 33385.499217512246, 33696.0, 32849.39199248616, 33203.0, 33657.15857103592, 34375.31249375048, 34449.827750861084, 32054.0, 32724.0, 32757.181070473427, 32904.34191316112, 32986.0, 23530.0, 27602.033928813198, 29455.0, 29629.40741185121, 30160.698393016668, 22146.22488212905, 22269.665955011013, 22575.984080716735, 22610.66084008774, 22684.432889177904], "value": 18801.872222829887, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.3535095424419, 370.70528929501063, 372.11993635053096, 379.11287587046314, 399.65030598226554, 13963.650908727286, 14533.345999430025, 18210.635787284245, 18404.72392914104, 19710.014499275014, 18915.905420472915, 19349.129289181987, 19414.417567472963, 19681.917494537796, 19682.22859942605, 5238.214267859823, 5621.578381621394, 5684.602077854552, 5695.515881150107, 5699.515541179005, 4024.4768180136543, 4247.980484683669, 5584.636998595099, 6065.181200537915, 6337.936620633781, 6385.425311721944, 6389.616623002611, 6395.20059992501, 6396.872062558746, 6407.903881441782, 6067.908981365283, 6290.182276304074, 6340.239171299474, 6349.36506349366, 6351.301356850743, 4683.929741053853, 5163.896722065575, 5469.863253418656, 5473.534749546293, 5485.506304432581, 23898.761012389827, 23920.162794302116, 24006.159784407635, 24193.15323963653, 24234.0, 2706.912562031327, 2722.101706436871, 2725.809193356466, 2730.30377253801, 2746.217328061493, 13964.301784910815, 14481.855181448156, 14788.556343309718, 15202.46791362308, 15221.391144354213, 1776.9289228430976, 1814.845738112262, 1828.7988321284647, 1831.6611426885936, 1834.1012903677235, 99192.0, 102021.0, 102587.0, 103031.0, 103577.48211258896, 10157.2925882054, 10206.540705668263, 10272.691819245434, 10473.31923424975, 10661.84007239892, 11760.235584686969, 11960.222585531912, 12384.81422778659, 12405.689857753448, 12665.936670316774, 6472.385123413268, 7825.765227043198, 7877.803054923673, 7915.84168316642, 8073.919260807317, 7829.843403131906, 7834.804129896798, 7841.882371764428, 7984.281414672707, 8027.959860200778, 5352.464753524694, 6129.417705317987, 6284.340144284784, 6294.748210071637, 6307.400796924377, 1740.6518696260546, 1831.9175637096096, 1858.2683455320657, 1859.2748827957107, 1859.3399343233227, 1035.5650626736788, 1041.8020576090519, 1046.4924511611862, 1050.415996505231, 1056.7622284985816, 20459.38621841347, 20486.38540843777, 20489.38531844047, 20522.794772052388, 20656.48358791042, 1034.1158309645145, 1045.5242864496636, 1062.4475272858072, 1062.4581463453694, 1069.385103565453, 8062.475939063942, 10087.0, 10491.944060273532, 10730.302530335503, 10938.398388088732, 286693.0, 326742.0, 539308.0, 551613.0, 553511.0, 80347.0, 98106.0, 98177.0, 99014.0, 100337.49831250797, 960.351762560283, 966.1256562810527, 981.0679854138597, 1031.4430207687915, 1032.5766435761452, 16948.94918914607, 17823.910880445514, 17834.643307134043, 17878.374256900945, 18251.908740456212, 50499.0, 54193.0, 54423.18365224446, 54981.72509137429, 55397.7230113863, 55827.720861395435, 70328.0, 71522.0, 72180.0, 73279.0, 6892.724291028303, 7008.684609192701, 7063.117110361205, 7749.651265692944, 7828.2954534092205, 10925.289856159325, 12023.27860328383, 12433.93783031079, 12456.318397016286, 12622.242665440102, 10416.031431042116, 10445.321054131457, 10789.298695584763, 10875.184361172785, 10926.890731092588, 9592.848858137068, 9942.403455792386, 10317.27779055459, 10536.209784266057, 10709.0, 9885.357451765612, 10977.780444390908, 10980.560777569128, 11153.0, 11220.848286134182, 6877.552959057645, 7077.32765387308, 7087.362137407658, 7214.170370407471, 7238.167610724834, 9650.565724542272, 10266.281360304707, 10427.582896684047, 10806.459677016275, 10877.51051202682], "value": 9238.850737402541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.9871601283987, 1373.6909195431028, 1395.8115654386654, 1395.839478459977, 1397.3502321420538, 8067.072286687026, 8408.285295749862, 8424.410291279613, 8424.452410593307, 8428.241458268754, 6227.782027629034, 7608.77173684789, 7879.80300492488, 8108.472949258294, 8179.754607361774, 2253.177590179585, 2302.481941563152, 2307.2962746362355, 2307.411610039446, 2316.1893337331967, 2268.353519247015, 2301.9309420717404, 2307.4116100394376, 2309.145616122029, 2315.7221133463922, 2018.7476565429322, 2104.105755054096, 2225.732912050556, 2271.6478945763433, 2291.014863608648, 2289.633658614622, 2293.415179129328, 2293.8738369389675, 2298.321995011476, 2300.769923007703, 2271.965920511194, 2285.1202287119577, 2294.7246330440453, 2300.459392042869, 2302.792748652629, 8124.065732440786, 8320.875186872201, 8344.707935222237, 8345.082040975487, 8347.95826020866, 4967.751612419364, 5125.641205115643, 5135.845924622267, 5142.640015198937, 5143.562797162245, 3215.6141263048585, 3222.96777032229, 3264.60824701035, 3457.2048428861503, 3529.2588556403184, 1174.1956759619688, 1218.530865616744, 1240.6340129661771, 1257.9119461637688, 1276.6872116331558, 10522.736931576695, 10671.466426678713, 10712.196585256137, 10722.0, 10730.141588672888, 3928.3518219493462, 4383.956160438354, 4399.956000440018, 4651.906961860744, 4652.464966528893, 9263.768405789775, 9342.439453632667, 9356.345055846026, 9364.391314564531, 9370.969193388722, 5035.269885866529, 5117.795288188505, 5132.769025393866, 5141.897162056738, 5147.408048074444, 4130.876073717794, 4408.294672852328, 4647.09381670571, 4674.976625116853, 4681.906361872743, 3705.9629403705617, 3739.2147648993473, 3789.7536660117003, 3827.923441531154, 3850.2299540092095, 1245.2590708528412, 1249.3565813606, 1253.3232054690366, 1261.1298204239047, 1266.9809952850715, 755.61463653536, 769.8498792735475, 887.5251740318898, 892.9732108036769, 936.948467834262, 5395.055865223649, 5593.580481463907, 5743.9425605744245, 5798.710064496759, 6088.360722124113, 862.4221771413169, 874.6501399440275, 967.3905439573165, 971.2618410008445, 992.1913640383065, 3544.8936531904083, 3583.390823559949, 3650.872219472306, 3870.07118291608, 3875.3799392097694, 9262.027487113755, 9321.627134914663, 9515.333926625208, 9682.273829462818, 9946.254030947708, 9782.168515676247, 9794.804103917882, 9800.166985806287, 9807.803843923082, 9816.558254878548, 7332.853342933112, 7767.650455729506, 7865.528068315805, 7869.881951770728, 7881.211878812188, 5417.889332686711, 5657.179708942263, 5671.461211184931, 5738.799142030009, 5790.623609465371, 9084.545772711206, 9123.635054597742, 9243.953780231055, 9258.074192580689, 9287.303452241244, 9311.44131352121, 9326.813463730552, 9346.859797103185, 9348.485833279236, 9514.666986655433, 6648.800535983928, 6780.898286525608, 6796.966015169893, 7060.223375428802, 7231.0, 7763.844723105619, 7895.64469598858, 7965.322947549524, 7982.84034319322, 8002.399820013406, 7052.894206587008, 7126.5011449198055, 7163.3552980232025, 7221.963890180516, 7301.817454563678, 6949.861002780017, 6984.161900571964, 6999.0551275577145, 7093.361597456254, 7159.212486626363, 6339.397757213057, 6341.334159913143, 6419.0, 6421.8715625688155, 6438.291787903233, 4760.59534939534, 4789.281607758864, 4808.326834243138, 4836.274558816206, 4917.483664215349, 4676.953230467651, 4708.835190768306, 4776.140294746979, 4839.201531747222, 4952.281919121635], "value": 4362.304210760215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.99902294581824, 349.0871371363883, 359.46260340790525, 366.4082506751597, 368.76951905059354, 4634.212183928732, 4677.848189832673, 5085.702581318779, 5281.366236051669, 5301.284326615911, 4597.804570811588, 4990.3762029746285, 5085.10989093343, 5169.457206993268, 5293.444188360225, 3623.0797677231108, 3953.268645300614, 4134.235166494193, 4136.710430269883, 4156.711419459977, 3474.096734848932, 4125.195586860564, 4139.3583994480905, 4141.088960428701, 4161.396597493362, 3570.7143428525787, 3573.94639080414, 4009.1981603679237, 4111.465509483763, 4125.401816736571, 3424.4863270509372, 4056.7971601419813, 4099.344104943195, 4130.256553820296, 4133.1940271647045, 3455.706264967481, 3630.8003059831963, 3635.94546081809, 3646.9270614587826, 3657.1771351445977, 5127.66670166438, 5128.230765385185, 5161.470879269169, 5171.3277273954145, 5171.50870667286, 3851.1334949636107, 4070.430139780433, 4574.644058309973, 4779.972305954202, 4821.493743156953, 4990.326305948687, 5464.480874316973, 5622.859428514279, 5647.491725744702, 5669.8582535436535, 3152.921176970594, 3228.5480032795426, 3695.9076023099637, 4022.8937042313596, 4039.9596004039877, 4982.234859652891, 5032.84901452957, 5721.027425337703, 5816.476517113438, 5890.556813580614, 3932.2528719543197, 3966.8809935701975, 3967.5238971323624, 3972.1330974442544, 3988.66096381805, 4023.195360927826, 4034.0318323602123, 4040.2323558523785, 4040.777757223322, 4054.2904991626347, 4380.0, 4573.862784116415, 4668.50980647034, 4674.691086495786, 4685.8594242172785, 4499.437570303712, 5619.623192317827, 5634.0, 5637.746301416364, 5660.97169514158, 1356.905016648825, 1358.8165597644388, 1368.931553422325, 1370.4792178971927, 1379.3861731529573, 899.2311573604604, 920.4799288402031, 924.2190349154953, 953.594722243044, 966.3815158298695, 4986.102501549756, 5015.645775640556, 5042.773075211624, 5053.418856831438, 5061.8734531637, 5117.948820511821, 5455.754491047913, 5474.479924407175, 5487.835364938978, 5586.854694787558, 4347.478462491747, 4617.3304870793545, 4859.562639362403, 4970.950290497121, 5045.419776725724, 2421.6630951132242, 2480.9331987245296, 2521.571332873416, 2547.668882972097, 2793.9662324939904, 4767.308740232646, 5067.594592432671, 5438.809641662523, 5540.97491963988, 5599.776008959678, 4701.694389864648, 4725.1880187369725, 5006.749662516932, 5040.420351659533, 5609.298837645294, 4907.460179380265, 5102.795888164506, 5248.8425347239645, 5522.723863806794, 5647.5199608032135, 4535.9773201133785, 5113.731493219135, 5298.364196296469, 5351.678899266057, 5484.698341591091, 4480.75355855418, 4710.8351207707565, 5262.710550919736, 5329.73351332424, 5376.73116344189, 4862.343583616166, 5499.670019798825, 5640.769422932801, 5645.745941432728, 5695.401982791747, 3882.3793939090438, 3893.9221215576094, 3895.435161901452, 3903.7117751142623, 3960.663343615825, 5117.488251174853, 5331.97334013343, 5459.771551400903, 5613.410591887793, 5778.422157784188, 5000.399872041057, 5211.9478805211465, 5544.695041772741, 5653.180288858175, 5666.518345940476, 4744.454387745315, 5184.148718205048, 5184.714840683799, 5583.994880921311, 5606.327240730975, 5214.9478505214465, 5328.907573947254, 5421.728913554385, 5445.700486473282, 5454.263674404064, 4399.130369592846, 5032.572231360376, 5379.112446446293, 5411.891762164813, 5661.547076233974, 4655.976720116378, 5230.293910322058, 5363.141897296414, 5547.833564993057, 5550.639208451437], "value": 4025.0073877885793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.5048091576353, 723.7720118162781, 765.6784150656723, 795.4392153531759, 798.9426632412124, 14438.20589867557, 15293.84706152941, 15321.0, 15322.463713770007, 15341.38634454622, 14200.573982780506, 14357.138571685706, 14366.994310398277, 14380.352884120213, 14414.495492657732, 686.6909890549248, 708.3200127877218, 835.6114406800837, 858.8711693246016, 871.1375738019378, 867.7223288547667, 871.5337294547419, 872.5811610426999, 879.5909901895617, 880.8238352329528, 928.5774972387582, 929.6281487405024, 929.8047410043899, 930.8231436027133, 931.7391130483476, 726.9563826170447, 922.2760133295398, 924.8289066522715, 925.8240934222544, 930.7580029192391, 540.6972095626422, 540.7728753923361, 543.1364131031639, 548.4241546376289, 665.2316574356626, 14731.96876218665, 15165.620859478491, 15169.0, 15173.0, 15186.240687965557, 13577.660558486019, 13640.931795340959, 13666.0, 13669.87691815858, 13840.653983650389, 12585.433655485525, 12608.621741347682, 12623.242605443611, 12770.74458510834, 12867.292298923556, 4267.850625228133, 4530.252508336121, 4569.428821397325, 4589.128065667513, 4590.0, 109042.0, 110544.0, 113624.0, 114633.85366146314, 114750.85249147569, 10720.571177152982, 10953.780924381468, 10993.945030274797, 11013.889861101447, 11349.432528373549, 16503.0, 21524.0, 21832.89083554572, 21924.561508769737, 21983.890080549496, 7544.735934242385, 7586.886196707053, 7628.313451789366, 7661.961690191513, 7835.451518393772, 4074.30736774749, 4142.5857414258335, 4151.807948998929, 4155.667546596325, 4161.375793630979, 3385.779924304912, 3447.465642825366, 3454.5681789776277, 3481.686648201674, 3484.4773284007097, 672.4586707700348, 699.3076853914588, 701.2006312803409, 711.3704371631144, 711.9537230080028, 617.7220250887119, 661.1933441201726, 676.7394553096995, 677.4047119034602, 698.2912343970874, 7353.0, 8191.972285270544, 8773.561321933877, 8932.8660070099, 8987.19115279628, 616.2820314333841, 622.3135303328032, 634.1597383466925, 653.1639501438071, 657.552864052449, 3951.786999280355, 4349.3041113421705, 4357.869263922024, 4372.715773474714, 4425.336199570091, 106723.0, 108117.459412704, 108672.0, 110443.0, 111461.44269278602, 63419.68290158612, 94524.16427507048, 94844.52577737067, 97608.0239197599, 98056.50971745096, 81081.0, 81673.77489337664, 82276.0, 83273.58363208266, 84815.0, 7026.9648651756415, 8058.3150432213915, 8366.99596048478, 8506.609663612327, 8770.809213940493, 50986.0, 51761.0, 52240.7387963073, 52846.735766320926, 54315.91368172693, 70212.648936755, 70310.0, 71959.0, 72561.0, 73373.89939150811, 11550.48022838991, 11617.302961822317, 11713.355765432641, 11939.82090268664, 12056.51773929033, 12605.621831345074, 12613.0, 12624.0, 12683.42924568415, 13040.543580975, 12077.51689932393, 12164.756704865677, 12234.938825305817, 12260.203086799329, 12277.386130693609, 11804.527818887149, 11934.4032798358, 12069.75860482803, 12100.939495302468, 12310.630681079581, 11884.34636095023, 11951.0, 12035.638930832089, 12120.636380908587, 12366.567170149001, 9919.40483570988, 10224.693259202237, 10368.533415996148, 10411.583536658449, 10522.0, 9766.706998790047, 9813.460259685786, 9814.165795907427, 9818.45998470091, 9847.409155450696], "value": 7312.429554855952, "unit": "score"}], "name": "dromaeo_css", "value": 7089.963888463189, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 113.0, 113.0, 127.0, 165.0, 167.0, 118.0, 113.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 137.0, 177.0, 114.0, 111.0, 112.0, 113.0, 129.0, 113.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 184.0, 184.0, 185.0, 181.0, 180.0, 184.0, 184.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 58.0, 87.0, 61.0, 58.0, 58.0, 57.0, 74.0, 59.0], "value": 64.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 87.0, 69.0, 69.0, 70.0, 84.0, 70.0, 69.0, 83.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 88.0, 85.0, 89.0, 86.0, 88.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 137.0, 93.0, 106.0, 147.0, 123.0, 93.0, 93.0, 93.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 85.0, 85.0, 88.0, 82.0, 84.0, 87.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 54.0, 55.0, 55.0, 57.0, 57.0, 56.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 85.0, 77.0, 73.0, 72.0, 70.0, 72.0, 70.0, 98.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 86.0, 89.0, 87.0, 86.0, 83.0, 82.0, 100.0, 102.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 137.0, 141.0, 135.0, 131.0, 143.0, 147.0, 206.0, 151.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 170.0, 172.0, 168.0, 175.0, 173.0, 172.0, 171.0, 171.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 68.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1461.0, "alertThreshold": 2.0}]} 22:02:38 INFO - Return code: 0 22:02:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:02:38 INFO - # TBPL SUCCESS # 22:02:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:02:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:02:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:02:38 INFO - 2016-12-26 22:02:38.306 screenresolution[1664:10727] starting screenresolution argv=screenresolution get 22:02:38 INFO - 2016-12-26 22:02:38.309 screenresolution[1664:10727] Display 0: 1600x1200x32@60 22:02:38 INFO - 2016-12-26 22:02:38.315 screenresolution[1665:10730] starting screenresolution argv=screenresolution list 22:02:38 INFO - Available Modes on Display 0 22:02:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:02:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:02:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:02:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:02:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:02:38 INFO - Intel Iris: 22:02:38 INFO - Chipset Model: Intel Iris 22:02:38 INFO - Type: GPU 22:02:38 INFO - Bus: Built-In 22:02:38 INFO - VRAM (Dynamic, Max): 1536 MB 22:02:38 INFO - Vendor: Intel (0x8086) 22:02:38 INFO - Device ID: 0x0a2e 22:02:38 INFO - Revision ID: 0x0009 22:02:38 INFO - Displays: 22:02:38 INFO - Display: 22:02:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:02:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:02:38 INFO - Display Serial Number: 1600x1200 60 22:02:38 INFO - Main Display: Yes 22:02:38 INFO - Mirror: Off 22:02:38 INFO - Online: Yes 22:02:38 INFO - Rotation: Supported 22:02:38 INFO - Return code: 0 22:02:38 INFO - Running post-action listener: _resource_record_post_action 22:02:38 INFO - [mozharness: 2016-12-27 06:02:38.549336Z] Finished run-tests step (success) 22:02:38 INFO - Running post-run listener: _resource_record_post_run 22:02:38 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32140288; Write bytes: 552855552; Read time: 194; Write time: 1656 22:02:38 INFO - TinderboxPrint: CPU usage
53.9% 22:02:38 INFO - TinderboxPrint: I/O read bytes / time
32,140,288 / 194 22:02:38 INFO - TinderboxPrint: I/O write bytes / time
552,855,552 / 1,656 22:02:38 INFO - TinderboxPrint: CPU idle
2,218.6 (46.1%) 22:02:38 INFO - TinderboxPrint: CPU system
961.4 (20.0%) 22:02:38 INFO - TinderboxPrint: CPU user
1,629.6 (33.9%) 22:02:38 INFO - TinderboxPrint: Swap in / out
277,389,312 / 0 22:02:38 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250505728; Write bytes: 254600192; Read time: 18276; Write time: 571 22:02:38 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 28920832; Write bytes: 296915968; Read time: 171; Write time: 1079 22:02:39 INFO - Running post-run listener: _upload_blobber_files 22:02:39 INFO - Blob upload gear active. 22:02:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:02:39 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 22:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:02:39 INFO - (blobuploader) - INFO - Open directory for files ... 22:02:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:02:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:02:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:02:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:02:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:02:40 INFO - (blobuploader) - INFO - Done attempting. 22:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:02:41 INFO - (blobuploader) - INFO - Done attempting. 22:02:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:02:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:02:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:02:42 INFO - (blobuploader) - INFO - Done attempting. 22:02:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:02:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:02:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:02:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:02:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:02:44 INFO - (blobuploader) - INFO - Done attempting. 22:02:44 INFO - (blobuploader) - INFO - Iteration through files over. 22:02:44 INFO - Return code: 0 22:02:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:02:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:02:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3402a7852dc1d303db1f83b2c98d6b4d4243dc56be93608aefe926c5a2a2c1c48662e742b43b231117c4929cc4fc04f1ad48dee55f46a2bdd44666f463bcc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf7976408e130ea61b30fcf91a21b955ea5b263f977d13a42e2ff57dbe1d5c296b0b380a59d8369d6747bc04b591d1365b4acb65b509237fa19b5b7b7dedb19d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12be8dee176ef507c344c159fb6bba3c2f3fd33bda95d168fa12d647f75549d4396c9d024f9e1d5c810a4d6b3e27941334a08dc20a8d2a381f4e265c94c230e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4c1978274c0c6564fc88373777026480fa97ef783c6ff866c0257440e0e73be59da2991599e6265d9b0ab0336cdadd86a5c2483f5e5715aece75b16a99faa2d"} 22:02:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:02:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:02:44 INFO - Contents: 22:02:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3402a7852dc1d303db1f83b2c98d6b4d4243dc56be93608aefe926c5a2a2c1c48662e742b43b231117c4929cc4fc04f1ad48dee55f46a2bdd44666f463bcc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf7976408e130ea61b30fcf91a21b955ea5b263f977d13a42e2ff57dbe1d5c296b0b380a59d8369d6747bc04b591d1365b4acb65b509237fa19b5b7b7dedb19d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12be8dee176ef507c344c159fb6bba3c2f3fd33bda95d168fa12d647f75549d4396c9d024f9e1d5c810a4d6b3e27941334a08dc20a8d2a381f4e265c94c230e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4c1978274c0c6564fc88373777026480fa97ef783c6ff866c0257440e0e73be59da2991599e6265d9b0ab0336cdadd86a5c2483f5e5715aece75b16a99faa2d"} 22:02:44 INFO - Running post-run listener: copy_logs_to_upload_dir 22:02:44 INFO - Copying logs to upload dir... 22:02:44 INFO - mkdir: /builds/slave/test/build/upload/logs 22:02:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.404794 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-26 22:02:44.897629) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 22:02:44.901547) ========= 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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3402a7852dc1d303db1f83b2c98d6b4d4243dc56be93608aefe926c5a2a2c1c48662e742b43b231117c4929cc4fc04f1ad48dee55f46a2bdd44666f463bcc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf7976408e130ea61b30fcf91a21b955ea5b263f977d13a42e2ff57dbe1d5c296b0b380a59d8369d6747bc04b591d1365b4acb65b509237fa19b5b7b7dedb19d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12be8dee176ef507c344c159fb6bba3c2f3fd33bda95d168fa12d647f75549d4396c9d024f9e1d5c810a4d6b3e27941334a08dc20a8d2a381f4e265c94c230e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4c1978274c0c6564fc88373777026480fa97ef783c6ff866c0257440e0e73be59da2991599e6265d9b0ab0336cdadd86a5c2483f5e5715aece75b16a99faa2d"} build_url:https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010613 build_url: 'https://queue.taskcluster.net/v1/task/CNXqwxD5SUCVIONhYXRk8w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3402a7852dc1d303db1f83b2c98d6b4d4243dc56be93608aefe926c5a2a2c1c48662e742b43b231117c4929cc4fc04f1ad48dee55f46a2bdd44666f463bcc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf7976408e130ea61b30fcf91a21b955ea5b263f977d13a42e2ff57dbe1d5c296b0b380a59d8369d6747bc04b591d1365b4acb65b509237fa19b5b7b7dedb19d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12be8dee176ef507c344c159fb6bba3c2f3fd33bda95d168fa12d647f75549d4396c9d024f9e1d5c810a4d6b3e27941334a08dc20a8d2a381f4e265c94c230e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4c1978274c0c6564fc88373777026480fa97ef783c6ff866c0257440e0e73be59da2991599e6265d9b0ab0336cdadd86a5c2483f5e5715aece75b16a99faa2d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 22:02:44.936159) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:02:44.936505) ========= 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.bZqdHAvo6e/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.0O3ak5RX1B/Listeners TMPDIR=/var/folders/m8/xm1_j30s6pvgt5d6k446cwxc00000w/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.005033 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 22:02:44.985091) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 22:02:44.985422) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 22:02:44.985757) ========= ========= Total master_lag: 0.19 =========