builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0174 starttime: 1482841998.93 results: success (0) buildid: 20161227030213 builduid: 76d48b557275491e98fd1c6abcf390c6 revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:18.928079) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:18.928507) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:18.928801) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:18.954760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:18.955022) ========= 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.WX0lseo5at/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.XxaSC7bib8/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-27 04:33:18-- 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% 215M=0s 2016-12-27 04:33:19 (215 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.492644 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:19.501152) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:19.501451) ========= 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.WX0lseo5at/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.XxaSC7bib8/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.027783 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:19.550515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 04:33:19.550846) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --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 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WX0lseo5at/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.XxaSC7bib8/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-27 04:33:19,645 truncating revision to first 12 chars 2016-12-27 04:33:19,645 Setting DEBUG logging. 2016-12-27 04:33:19,645 attempt 1/10 2016-12-27 04:33:19,645 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3119a9a0b5de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 04:33:21,295 unpacking tar archive at: mozilla-central-3119a9a0b5de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.910596 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 04:33:21.484671) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:21.484942) ========= script_repo_revision: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:21.485314) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:21.485576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 04:33:21.505174) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-27 04:33:21.505441) ========= /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.WX0lseo5at/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.XxaSC7bib8/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:33:21 INFO - MultiFileLogger online at 20161227 04:33:21 in /builds/slave/test 04:33:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:33:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:33:21 INFO - {'append_to_log': False, 04:33:21 INFO - 'base_work_dir': '/builds/slave/test', 04:33:21 INFO - 'blob_upload_branch': 'Firefox', 04:33:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:33:21 INFO - 'branch': 'Firefox', 04:33:21 INFO - 'buildbot_json_path': 'buildprops.json', 04:33:21 INFO - 'config_files': ('talos/mac_config.py',), 04:33:21 INFO - 'default_actions': ('clobber', 04:33:21 INFO - 'read-buildbot-config', 04:33:21 INFO - 'download-and-extract', 04:33:21 INFO - 'populate-webroot', 04:33:21 INFO - 'create-virtualenv', 04:33:21 INFO - 'install', 04:33:21 INFO - 'run-tests'), 04:33:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:33:21 INFO - 'download_minidump_stackwalk': True, 04:33:21 INFO - 'download_symbols': 'ondemand', 04:33:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:33:21 INFO - 'tooltool.py': '/tools/tooltool.py', 04:33:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:33:21 INFO - '/tools/misc-python/virtualenv.py')}, 04:33:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:33:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:33:21 INFO - 'installer_path': 'installer.exe', 04:33:21 INFO - 'log_level': 'info', 04:33:21 INFO - 'log_name': 'talos', 04:33:21 INFO - 'log_to_console': True, 04:33:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:33:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:33:21 INFO - 'opt_config_files': (), 04:33:21 INFO - 'pip_index': False, 04:33:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:33:21 INFO - 'cmd': ('bash', 04:33:21 INFO - '-c', 04:33:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:33:21 INFO - 'enabled': True, 04:33:21 INFO - 'halt_on_failure': False, 04:33:21 INFO - 'name': 'check_screen_resolution'},), 04:33:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:33:21 INFO - 'cmd': ('bash', 04:33:21 INFO - '-c', 04:33:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:33:21 INFO - 'enabled': True, 04:33:21 INFO - 'halt_on_failure': False, 04:33:21 INFO - 'name': 'check_screen_resolution'},), 04:33:21 INFO - 'run_cmd_checks_enabled': True, 04:33:21 INFO - 'sps_profile': False, 04:33:21 INFO - 'sps_profile_interval': 0, 04:33:21 INFO - 'suite': 'dromaeojs', 04:33:21 INFO - 'system_bits': '32', 04:33:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:33:21 INFO - 'title': 't-yosemite-r7-0174', 04:33:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:33:21 INFO - 'use_talos_json': True, 04:33:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:33:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:33:21 INFO - 'work_dir': 'build'} 04:33:21 INFO - [mozharness: 2016-12-27 12:33:21.710028Z] Running clobber step. 04:33:21 INFO - Running pre-action listener: _resource_record_pre_action 04:33:21 INFO - Running main action method: clobber 04:33:21 INFO - rmtree: /builds/slave/test/build 04:33:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:33:24 INFO - Running post-action listener: _resource_record_post_action 04:33:24 INFO - [mozharness: 2016-12-27 12:33:24.267424Z] Finished clobber step (success) 04:33:24 INFO - [mozharness: 2016-12-27 12:33:24.267589Z] Running read-buildbot-config step. 04:33:24 INFO - Running pre-action listener: _resource_record_pre_action 04:33:24 INFO - Running main action method: read_buildbot_config 04:33:24 INFO - Using buildbot properties: 04:33:24 INFO - { 04:33:24 INFO - "project": "", 04:33:24 INFO - "product": "firefox", 04:33:24 INFO - "script_repo_revision": "production", 04:33:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:33:24 INFO - "repository": "", 04:33:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:33:24 INFO - "buildid": "20161227030213", 04:33:24 INFO - "stage_platform": "macosx64", 04:33:24 INFO - "basedir": "/builds/slave/test", 04:33:24 INFO - "nightly_build": "True", 04:33:24 INFO - "buildnumber": 60, 04:33:24 INFO - "slavename": "t-yosemite-r7-0174", 04:33:24 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:33:24 INFO - "platform": "yosemite_r7", 04:33:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:33:24 INFO - "branch": "mozilla-central", 04:33:24 INFO - "repo_path": "mozilla-central", 04:33:24 INFO - "revision": "3119a9a0b5dee60ac77b7596ae5dbe0658f598ad", 04:33:24 INFO - "pgo_build": "False", 04:33:24 INFO - "builduid": "76d48b557275491e98fd1c6abcf390c6", 04:33:24 INFO - "slavebuilddir": "test" 04:33:24 INFO - } 04:33:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:33:24 INFO - Running post-action listener: _resource_record_post_action 04:33:24 INFO - [mozharness: 2016-12-27 12:33:24.271151Z] Finished read-buildbot-config step (success) 04:33:24 INFO - [mozharness: 2016-12-27 12:33:24.271267Z] Running download-and-extract step. 04:33:24 INFO - Running pre-action listener: _resource_record_pre_action 04:33:24 INFO - Running main action method: download_and_extract 04:33:24 INFO - mkdir: /builds/slave/test/build/tests 04:33:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:24 INFO - trying https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:33:24 INFO - Downloading https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:33:25 INFO - Downloaded 1199 bytes. 04:33:25 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:33:25 INFO - Using the following test package requirements: 04:33:25 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:33:25 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:33:25 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:33:25 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'jsshell-mac.zip'], 04:33:25 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:33:25 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:33:25 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:33:25 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:33:25 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:33:25 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:33:25 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:33:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:33:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:33:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:33:25 INFO - Fetch https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:33:26 INFO - Expected file size: 16294558 04:33:26 INFO - Obtained file size: 16294558 04:33:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:33:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:33:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:33:32 INFO - Fetch https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:33:33 INFO - Expected file size: 16294558 04:33:33 INFO - Obtained file size: 16294558 04:33:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:33:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:33:39 INFO - Fetch https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:33:41 INFO - Expected file size: 14409776 04:33:41 INFO - Obtained file size: 14409776 04:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:41 INFO - trying https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:33:41 INFO - Downloading https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:33:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:33:43 INFO - Downloaded 69696838 bytes. 04:33:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:33:43 INFO - mkdir: /builds/slave/test/properties 04:33:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:33:43 INFO - Writing to file /builds/slave/test/properties/build_url 04:33:43 INFO - Contents: 04:33:43 INFO - build_url:https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:33:44 INFO - Running post-action listener: _resource_record_post_action 04:33:44 INFO - Running post-action listener: set_extra_try_arguments 04:33:44 INFO - [mozharness: 2016-12-27 12:33:44.289904Z] Finished download-and-extract step (success) 04:33:44 INFO - [mozharness: 2016-12-27 12:33:44.290035Z] Running populate-webroot step. 04:33:44 INFO - Running pre-action listener: _resource_record_pre_action 04:33:44 INFO - Running main action method: populate_webroot 04:33:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:33:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:33:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:33:44 INFO - '--tppagecycles', 04:33:44 INFO - '7'], 04:33:44 INFO - 'tests': ['tsvgm']}}, 04:33:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:33:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:33:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:33:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:33:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:33:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:33:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['damp', 'tps']}, 04:33:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'tests': ['damp', 'tps']}, 04:33:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['dromaeo_dom']}, 04:33:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:33:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['basic_compositor_video']}, 04:33:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:33:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['a11yr', 04:33:44 INFO - 'ts_paint', 04:33:44 INFO - 'tpaint', 04:33:44 INFO - 'sessionrestore', 04:33:44 INFO - 'sessionrestore_no_auto_restore', 04:33:44 INFO - 'tabpaint']}, 04:33:44 INFO - 'other-e10s': {'tests': ['a11yr', 04:33:44 INFO - 'ts_paint', 04:33:44 INFO - 'tpaint', 04:33:44 INFO - 'sessionrestore', 04:33:44 INFO - 'sessionrestore_no_auto_restore', 04:33:44 INFO - 'tabpaint']}, 04:33:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:33:44 INFO - 'ts_paint', 04:33:44 INFO - 'tpaint', 04:33:44 INFO - 'sessionrestore', 04:33:44 INFO - 'sessionrestore_no_auto_restore', 04:33:44 INFO - 'tabpaint']}, 04:33:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:33:44 INFO - 'ts_paint', 04:33:44 INFO - 'tpaint', 04:33:44 INFO - 'sessionrestore', 04:33:44 INFO - 'sessionrestore_no_auto_restore', 04:33:44 INFO - 'tabpaint']}, 04:33:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['a11yr', 04:33:44 INFO - 'ts_paint', 04:33:44 INFO - 'tpaint', 04:33:44 INFO - 'sessionrestore', 04:33:44 INFO - 'sessionrestore_no_auto_restore', 04:33:44 INFO - 'tabpaint']}, 04:33:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['a11yr', 04:33:44 INFO - 'ts_paint', 04:33:44 INFO - 'tpaint', 04:33:44 INFO - 'sessionrestore', 04:33:44 INFO - 'sessionrestore_no_auto_restore', 04:33:44 INFO - 'tabpaint']}, 04:33:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['tsvgx', 04:33:44 INFO - 'tsvgr_opacity', 04:33:44 INFO - 'tart', 04:33:44 INFO - 'tscrollx', 04:33:44 INFO - 'cart', 04:33:44 INFO - 'tsvg_static']}, 04:33:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:33:44 INFO - 'tsvgr_opacity', 04:33:44 INFO - 'tart', 04:33:44 INFO - 'tscrollx', 04:33:44 INFO - 'cart', 04:33:44 INFO - 'tsvg_static']}, 04:33:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'talos_options': ['--disable-e10s'], 04:33:44 INFO - 'tests': ['tp5o']}, 04:33:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:33:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'talos_options': ['--disable-e10s', 04:33:44 INFO - '--xperf_path', 04:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:33:44 INFO - 'tests': ['tp5n']}, 04:33:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:33:44 INFO - 'talos_options': ['--xperf_path', 04:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:33:44 INFO - 'tests': ['tp5n']}}, 04:33:44 INFO - 'talos.zip': {'path': '', 04:33:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:33:44 INFO - Running post-action listener: _resource_record_post_action 04:33:44 INFO - [mozharness: 2016-12-27 12:33:44.308713Z] Finished populate-webroot step (success) 04:33:44 INFO - [mozharness: 2016-12-27 12:33:44.308846Z] Running create-virtualenv step. 04:33:44 INFO - Running pre-action listener: _resource_record_pre_action 04:33:44 INFO - Running main action method: create_virtualenv 04:33:44 INFO - Creating virtualenv /builds/slave/test/build/venv 04:33:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:33:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:33:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:33:44 INFO - Using real prefix '/tools/python27' 04:33:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:33:45 INFO - Installing distribute.............................................................................................................................................................................................done. 04:33:48 INFO - Installing pip.................done. 04:33:48 INFO - Return code: 0 04:33:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:33:48 INFO - Reading from file tmpfile_stdout 04:33:48 INFO - Output received: 04:33:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:33:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:48 INFO - 'HOME': '/Users/cltbld', 04:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:48 INFO - 'LOGNAME': 'cltbld', 04:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:48 INFO - 'MOZ_NO_REMOTE': '1', 04:33:48 INFO - 'NO_EM_RESTART': '1', 04:33:48 INFO - 'PAGER': '/bin/cat', 04:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:48 INFO - 'PWD': '/builds/slave/test', 04:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:48 INFO - 'SHELL': '/bin/bash', 04:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:33:48 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:48 INFO - 'USER': 'cltbld', 04:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:48 INFO - 'XPC_FLAGS': '0x0', 04:33:48 INFO - 'XPC_SERVICE_NAME': '0', 04:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:33:48 INFO - Cleaning up... 04:33:48 INFO - Return code: 0 04:33:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:33:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:48 INFO - 'HOME': '/Users/cltbld', 04:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:48 INFO - 'LOGNAME': 'cltbld', 04:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:48 INFO - 'MOZ_NO_REMOTE': '1', 04:33:48 INFO - 'NO_EM_RESTART': '1', 04:33:48 INFO - 'PAGER': '/bin/cat', 04:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:48 INFO - 'PWD': '/builds/slave/test', 04:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:48 INFO - 'SHELL': '/bin/bash', 04:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:33:48 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:48 INFO - 'USER': 'cltbld', 04:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:48 INFO - 'XPC_FLAGS': '0x0', 04:33:48 INFO - 'XPC_SERVICE_NAME': '0', 04:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:48 INFO - Downloading/unpacking psutil>=3.1.1 04:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:33:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:33:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:33:52 INFO - Installing collected packages: psutil 04:33:52 INFO - Running setup.py install for psutil 04:33:53 INFO - building 'psutil._psutil_osx' extension 04:33:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:33:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:33:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:33:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:33:53 INFO - building 'psutil._psutil_posix' extension 04:33:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:33:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:33:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:33:53 INFO - ^ 04:33:54 INFO - 1 warning generated. 04:33:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:33:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:33:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:33:54 INFO - Successfully installed psutil 04:33:54 INFO - Cleaning up... 04:33:54 INFO - Return code: 0 04:33:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:54 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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:33:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:54 INFO - 'HOME': '/Users/cltbld', 04:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:54 INFO - 'LOGNAME': 'cltbld', 04:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:54 INFO - 'MOZ_NO_REMOTE': '1', 04:33:54 INFO - 'NO_EM_RESTART': '1', 04:33:54 INFO - 'PAGER': '/bin/cat', 04:33:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:54 INFO - 'PWD': '/builds/slave/test', 04:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:54 INFO - 'SHELL': '/bin/bash', 04:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:33:54 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:54 INFO - 'USER': 'cltbld', 04:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:54 INFO - 'XPC_FLAGS': '0x0', 04:33:54 INFO - 'XPC_SERVICE_NAME': '0', 04:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:33:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:33:57 INFO - Installing collected packages: mozsystemmonitor 04:33:57 INFO - Running setup.py install for mozsystemmonitor 04:33:57 INFO - Successfully installed mozsystemmonitor 04:33:57 INFO - Cleaning up... 04:33:57 INFO - Return code: 0 04:33:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:33:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:33:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:33:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:33:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:33:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:33:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:33:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:33:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:33:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:33:57 INFO - 'HOME': '/Users/cltbld', 04:33:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:33:57 INFO - 'LOGNAME': 'cltbld', 04:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:33:57 INFO - 'MOZ_NO_REMOTE': '1', 04:33:57 INFO - 'NO_EM_RESTART': '1', 04:33:57 INFO - 'PAGER': '/bin/cat', 04:33:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:33:57 INFO - 'PWD': '/builds/slave/test', 04:33:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:33:57 INFO - 'SHELL': '/bin/bash', 04:33:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:33:57 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:33:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:33:57 INFO - 'USER': 'cltbld', 04:33:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:33:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:33:57 INFO - 'XPC_FLAGS': '0x0', 04:33:57 INFO - 'XPC_SERVICE_NAME': '0', 04:33:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:33:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:33:57 INFO - Downloading/unpacking jsonschema==2.5.1 04:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:33:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:33:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:34:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:00 INFO - Downloading functools32-3.2.3-2.tar.gz 04:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:34:00 INFO - warning: no files found matching '*.txt' 04:34:00 INFO - no previously-included directories found matching 'build' 04:34:00 INFO - no previously-included directories found matching 'dist' 04:34:00 INFO - no previously-included directories found matching '.git*' 04:34:00 INFO - Installing collected packages: jsonschema, functools32 04:34:00 INFO - Running setup.py install for functools32 04:34:00 INFO - warning: no files found matching '*.txt' 04:34:00 INFO - no previously-included directories found matching 'build' 04:34:00 INFO - no previously-included directories found matching 'dist' 04:34:00 INFO - no previously-included directories found matching '.git*' 04:34:00 INFO - Successfully installed jsonschema functools32 04:34:00 INFO - Cleaning up... 04:34:00 INFO - Return code: 0 04:34:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:00 INFO - 'HOME': '/Users/cltbld', 04:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:00 INFO - 'LOGNAME': 'cltbld', 04:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:00 INFO - 'MOZ_NO_REMOTE': '1', 04:34:00 INFO - 'NO_EM_RESTART': '1', 04:34:00 INFO - 'PAGER': '/bin/cat', 04:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:00 INFO - 'PWD': '/builds/slave/test', 04:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:00 INFO - 'SHELL': '/bin/bash', 04:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:00 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:00 INFO - 'USER': 'cltbld', 04:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:00 INFO - 'XPC_FLAGS': '0x0', 04:34:00 INFO - 'XPC_SERVICE_NAME': '0', 04:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:34:01 INFO - Cleaning up... 04:34:01 INFO - Return code: 0 04:34:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:01 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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:01 INFO - 'HOME': '/Users/cltbld', 04:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:01 INFO - 'LOGNAME': 'cltbld', 04:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:01 INFO - 'MOZ_NO_REMOTE': '1', 04:34:01 INFO - 'NO_EM_RESTART': '1', 04:34:01 INFO - 'PAGER': '/bin/cat', 04:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:01 INFO - 'PWD': '/builds/slave/test', 04:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:01 INFO - 'SHELL': '/bin/bash', 04:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:01 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:01 INFO - 'USER': 'cltbld', 04:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:01 INFO - 'XPC_FLAGS': '0x0', 04:34:01 INFO - 'XPC_SERVICE_NAME': '0', 04:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:01 INFO - Downloading/unpacking blobuploader==1.2.4 04:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:04 INFO - Downloading blobuploader-1.2.4.tar.gz 04:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:34:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:34:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:05 INFO - Downloading docopt-0.6.1.tar.gz 04:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:34:05 INFO - Installing collected packages: blobuploader, requests, docopt 04:34:05 INFO - Running setup.py install for blobuploader 04:34:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:34:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:34:05 INFO - Running setup.py install for requests 04:34:06 INFO - Running setup.py install for docopt 04:34:06 INFO - Successfully installed blobuploader requests docopt 04:34:06 INFO - Cleaning up... 04:34:06 INFO - Return code: 0 04:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:06 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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:06 INFO - 'HOME': '/Users/cltbld', 04:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:06 INFO - 'LOGNAME': 'cltbld', 04:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:06 INFO - 'MOZ_NO_REMOTE': '1', 04:34:06 INFO - 'NO_EM_RESTART': '1', 04:34:06 INFO - 'PAGER': '/bin/cat', 04:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:06 INFO - 'PWD': '/builds/slave/test', 04:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:06 INFO - 'SHELL': '/bin/bash', 04:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:06 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:06 INFO - 'USER': 'cltbld', 04:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:06 INFO - 'XPC_FLAGS': '0x0', 04:34:06 INFO - 'XPC_SERVICE_NAME': '0', 04:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:34:06 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Euz7gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:34:06 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-YyoJmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:34:06 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-L8DsT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:34:06 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-UE22Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-dbsYJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-XtZb7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-dW3yMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-AHl09Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-hWQAmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-52g9fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Bhn9pJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-tsKa8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-eHhey3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:34:07 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-fRusHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:34:08 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-iZk6L5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:34:08 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ivs6gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:34:08 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-J1XqOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:34:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:34:08 INFO - Running setup.py install for manifestparser 04:34:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:34:08 INFO - Running setup.py install for mozcrash 04:34:08 INFO - Running setup.py install for mozdebug 04:34:08 INFO - Running setup.py install for mozdevice 04:34:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:34:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Running setup.py install for mozfile 04:34:09 INFO - Running setup.py install for mozhttpd 04:34:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Running setup.py install for mozinfo 04:34:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Running setup.py install for mozInstall 04:34:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Running setup.py install for mozleak 04:34:09 INFO - Running setup.py install for mozlog 04:34:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:34:09 INFO - Running setup.py install for moznetwork 04:34:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:34:10 INFO - Running setup.py install for mozprocess 04:34:10 INFO - Running setup.py install for mozprofile 04:34:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:34:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:34:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:34:10 INFO - Running setup.py install for mozrunner 04:34:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:34:10 INFO - Running setup.py install for mozscreenshot 04:34:10 INFO - Running setup.py install for moztest 04:34:10 INFO - Running setup.py install for mozversion 04:34:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:34:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:34:10 INFO - Cleaning up... 04:34:10 INFO - Return code: 0 04:34:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:10 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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:34:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:34:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:10 INFO - 'HOME': '/Users/cltbld', 04:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:10 INFO - 'LOGNAME': 'cltbld', 04:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:10 INFO - 'MOZ_NO_REMOTE': '1', 04:34:10 INFO - 'NO_EM_RESTART': '1', 04:34:10 INFO - 'PAGER': '/bin/cat', 04:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:10 INFO - 'PWD': '/builds/slave/test', 04:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:10 INFO - 'SHELL': '/bin/bash', 04:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:10 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:10 INFO - 'USER': 'cltbld', 04:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:10 INFO - 'XPC_FLAGS': '0x0', 04:34:10 INFO - 'XPC_SERVICE_NAME': '0', 04:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-7mof78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ENlhCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-AdzNTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-pvLqTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-YLVlpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-uNMzr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Z2seaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:34:11 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-XW5D24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-92IdWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-dqbgw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-7Tq91l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-m0byMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-LJNZ0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-mHaNzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-SuZhAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-A9_SvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:34:12 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)) 04:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:34:12 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-YE1Ear-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:34:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:34:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:15 INFO - Downloading blessings-1.6.tar.gz 04:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:34:15 INFO - Installing collected packages: blessings 04:34:15 INFO - Running setup.py install for blessings 04:34:15 INFO - Successfully installed blessings 04:34:15 INFO - Cleaning up... 04:34:15 INFO - Return code: 0 04:34:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:34:16 INFO - Reading from file tmpfile_stdout 04:34:16 INFO - Current package versions: 04:34:16 INFO - blessings == 1.6 04:34:16 INFO - blobuploader == 1.2.4 04:34:16 INFO - docopt == 0.6.1 04:34:16 INFO - functools32 == 3.2.3-2 04:34:16 INFO - jsonschema == 2.5.1 04:34:16 INFO - manifestparser == 1.1 04:34:16 INFO - mozInstall == 1.12 04:34:16 INFO - mozcrash == 1.0 04:34:16 INFO - mozdebug == 0.1 04:34:16 INFO - mozdevice == 0.49 04:34:16 INFO - mozfile == 1.2 04:34:16 INFO - mozhttpd == 0.7 04:34:16 INFO - mozinfo == 0.9 04:34:16 INFO - mozleak == 0.1 04:34:16 INFO - mozlog == 3.4 04:34:16 INFO - moznetwork == 0.27 04:34:16 INFO - mozprocess == 0.23 04:34:16 INFO - mozprofile == 0.28 04:34:16 INFO - mozrunner == 6.13 04:34:16 INFO - mozscreenshot == 0.1 04:34:16 INFO - mozsystemmonitor == 0.3 04:34:16 INFO - moztest == 0.7 04:34:16 INFO - mozversion == 1.4 04:34:16 INFO - psutil == 3.1.1 04:34:16 INFO - requests == 1.2.3 04:34:16 INFO - wsgiref == 0.1.2 04:34:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:16 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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:16 INFO - 'HOME': '/Users/cltbld', 04:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:16 INFO - 'LOGNAME': 'cltbld', 04:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:16 INFO - 'MOZ_NO_REMOTE': '1', 04:34:16 INFO - 'NO_EM_RESTART': '1', 04:34:16 INFO - 'PAGER': '/bin/cat', 04:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:16 INFO - 'PWD': '/builds/slave/test', 04:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:16 INFO - 'SHELL': '/bin/bash', 04:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:16 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:16 INFO - 'USER': 'cltbld', 04:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:16 INFO - 'XPC_FLAGS': '0x0', 04:34:16 INFO - 'XPC_SERVICE_NAME': '0', 04:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:34:16 INFO - Cleaning up... 04:34:16 INFO - Return code: 0 04:34:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:16 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 0x10887ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0bc387a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, '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.WX0lseo5at/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.XxaSC7bib8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:16 INFO - 'HOME': '/Users/cltbld', 04:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:16 INFO - 'LOGNAME': 'cltbld', 04:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:16 INFO - 'MOZ_NO_REMOTE': '1', 04:34:16 INFO - 'NO_EM_RESTART': '1', 04:34:16 INFO - 'PAGER': '/bin/cat', 04:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:16 INFO - 'PWD': '/builds/slave/test', 04:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:16 INFO - 'SHELL': '/bin/bash', 04:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:16 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:16 INFO - 'USER': 'cltbld', 04:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:16 INFO - 'XPC_FLAGS': '0x0', 04:34:16 INFO - 'XPC_SERVICE_NAME': '0', 04:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:34:16 INFO - Cleaning up... 04:34:16 INFO - Return code: 0 04:34:16 INFO - Running post-action listener: _resource_record_post_action 04:34:16 INFO - Running post-action listener: _start_resource_monitoring 04:34:16 INFO - Starting resource monitoring. 04:34:16 INFO - [mozharness: 2016-12-27 12:34:16.811979Z] Finished create-virtualenv step (success) 04:34:16 INFO - [mozharness: 2016-12-27 12:34:16.812917Z] Running install step. 04:34:16 INFO - Running pre-action listener: _resource_record_pre_action 04:34:16 INFO - Running main action method: install 04:34:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:34:17 INFO - Reading from file tmpfile_stdout 04:34:17 INFO - Detecting whether we're running mozinstall >=1.0... 04:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:34:17 INFO - Reading from file tmpfile_stdout 04:34:17 INFO - Output received: 04:34:17 INFO - Usage: mozinstall [options] installer 04:34:17 INFO - Options: 04:34:17 INFO - -h, --help show this help message and exit 04:34:17 INFO - -d DEST, --destination=DEST 04:34:17 INFO - Directory to install application into. [default: 04:34:17 INFO - "/builds/slave/test"] 04:34:17 INFO - --app=APP Application being installed. [default: firefox] 04:34:17 INFO - mkdir: /builds/slave/test/build/application 04:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:34:39 INFO - Reading from file tmpfile_stdout 04:34:39 INFO - Output received: 04:34:39 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:34:39 INFO - Running post-action listener: _resource_record_post_action 04:34:39 INFO - [mozharness: 2016-12-27 12:34:39.184783Z] Finished install step (success) 04:34:39 INFO - [mozharness: 2016-12-27 12:34:39.184967Z] Running run-tests step. 04:34:39 INFO - Running pre-action listener: _resource_record_pre_action 04:34:39 INFO - Running main action method: run_tests 04:34:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:34:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:34:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:34:39 INFO - 2016-12-27 04:34:39.199 screenresolution[1670:14255] starting screenresolution argv=screenresolution get 04:34:39 INFO - 2016-12-27 04:34:39.202 screenresolution[1670:14255] Display 0: 1600x1200x32@60 04:34:39 INFO - 2016-12-27 04:34:39.207 screenresolution[1671:14257] starting screenresolution argv=screenresolution list 04:34:39 INFO - Available Modes on Display 0 04:34:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:34:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:34:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:34:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:34:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:34:39 INFO - Intel Iris: 04:34:39 INFO - Chipset Model: Intel Iris 04:34:39 INFO - Type: GPU 04:34:39 INFO - Bus: Built-In 04:34:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:34:39 INFO - Vendor: Intel (0x8086) 04:34:39 INFO - Device ID: 0x0a2e 04:34:39 INFO - Revision ID: 0x0009 04:34:39 INFO - Displays: 04:34:39 INFO - Display: 04:34:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:34:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:34:39 INFO - Display Serial Number: 1600x1200 60 04:34:39 INFO - Main Display: Yes 04:34:39 INFO - Mirror: Off 04:34:39 INFO - Online: Yes 04:34:39 INFO - Rotation: Supported 04:34:39 INFO - Return code: 0 04:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:34:39 INFO - Python 2.7.3 04:34:39 INFO - Return code: 0 04:34:39 INFO - grabbing minidump binary from tooltool 04:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:39 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 0x108658d30>, '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 0x10857fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10864dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:34:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:34:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:34:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:34:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:34:39 INFO - Return code: 0 04:34:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:34:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:34:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:34:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:34:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0174 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:34:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WX0lseo5at/Render', 04:34:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:39 INFO - 'HOME': '/Users/cltbld', 04:34:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:39 INFO - 'LOGNAME': 'cltbld', 04:34:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:34:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:39 INFO - 'MOZ_NO_REMOTE': '1', 04:34:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:34:39 INFO - 'NO_EM_RESTART': '1', 04:34:39 INFO - 'PAGER': '/bin/cat', 04:34:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:39 INFO - 'PWD': '/builds/slave/test', 04:34:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:34:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:39 INFO - 'SHELL': '/bin/bash', 04:34:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XxaSC7bib8/Listeners', 04:34:39 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 04:34:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:39 INFO - 'USER': 'cltbld', 04:34:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:39 INFO - 'XPC_FLAGS': '0x0', 04:34:39 INFO - 'XPC_SERVICE_NAME': '0', 04:34:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:34:39 INFO - mozversion application_buildid: 20161227030213 04:34:39 INFO - mozversion application_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 04:34:39 INFO - mozversion application_display_name: Nightly 04:34:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:34:39 INFO - mozversion application_name: Firefox 04:34:39 INFO - mozversion application_remotingname: firefox 04:34:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:34:39 INFO - mozversion application_vendor: Mozilla 04:34:39 INFO - mozversion application_version: 53.0a1 04:34:39 INFO - mozversion platform_buildid: 20161227030213 04:34:39 INFO - mozversion platform_changeset: 3119a9a0b5dee60ac77b7596ae5dbe0658f598ad 04:34:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:34:39 INFO - mozversion platform_version: 53.0a1 04:34:39 INFO - using testdate: 1482842079 04:34:39 INFO - actual date: 1482842079 04:34:39 INFO - starting webserver on 'localhost:49240' 04:34:39 INFO - SUITE-START | Running 2 tests 04:34:39 INFO - TEST-START | dromaeo_css 04:34:39 INFO - operating with platform_type : mac_ 04:34:39 INFO - Initialising browser for dromaeo_css test... 04:34:39 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmp4r5WCz/profile http://localhost:49240/getInfo.html) 04:34:40 INFO - PROCESS | 1681 | 1482842080464 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm: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 04:34:42 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 04:34:42 INFO - PROCESS | 1681 | colorDepth:24 04:34:42 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 04:34:42 INFO - PROCESS | 1681 | __metrics 04:34:42 INFO - PROCESS | 1681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:34:42 INFO - PROCESS | 1681 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:34:43 INFO - TEST-INFO | 1681: exit 0 04:34:43 INFO - Browser initialized. 04:34:43 INFO - Running cycle 1/1 for dromaeo_css test... 04:34:43 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmp4r5WCz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:34:55 INFO - PROCESS | 1685 | RSS: Main: 232976384 04:34:55 INFO - PROCESS | 1685 | 04:38:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 04:38:01 INFO - PROCESS | 1685 | RSS: Main: 377921536 04:38:01 INFO - PROCESS | 1685 | 04:39:43 INFO - PROCESS | 1685 | ************************* 04:39:43 INFO - PROCESS | 1685 | A coding exception was thrown and uncaught in a Task. 04:39:43 INFO - PROCESS | 1685 | 04:39:43 INFO - PROCESS | 1685 | Full message: ReferenceError: fetch is not defined 04:39:43 INFO - PROCESS | 1685 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:39:43 INFO - PROCESS | 1685 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:39:43 INFO - PROCESS | 1685 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:39:43 INFO - PROCESS | 1685 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:39:43 INFO - PROCESS | 1685 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:39:43 INFO - PROCESS | 1685 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:39:43 INFO - PROCESS | 1685 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:39:43 INFO - PROCESS | 1685 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:39:43 INFO - PROCESS | 1685 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:39:43 INFO - PROCESS | 1685 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:39:43 INFO - PROCESS | 1685 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:39:43 INFO - PROCESS | 1685 | 04:39:43 INFO - PROCESS | 1685 | ************************* 04:41:04 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:41:05 INFO - PROCESS | 1685 | RSS: Main: 367271936 04:41:05 INFO - PROCESS | 1685 | 04:44:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 04:44:08 INFO - PROCESS | 1685 | RSS: Main: 339546112 04:44:08 INFO - PROCESS | 1685 | 04:47:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 04:47:11 INFO - PROCESS | 1685 | RSS: Main: 344793088 04:47:11 INFO - PROCESS | 1685 | 04:50:14 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 04:50:15 INFO - PROCESS | 1685 | RSS: Main: 342552576 04:50:15 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 04:53:18 INFO - PROCESS | 1685 | RSS: Main: 443969536 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | __start_tp_report 04:53:18 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 04:53:18 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 04:53:18 INFO - PROCESS | 1685 | |i|pagename|runs| 04:53:18 INFO - PROCESS | 1685 | |0;dojo.html;2699.419624780672;3335.8665653373882;3346.928982725528;3353.8323083845803;3409.4203985322492;5496.945030549703;5608.887822243552;5668.886622267557;5731.226284451598;5741.569382296324;4681.297805329203;5662.207290979277;5664.631798933066;5722.313322401307;5737.713114344287;5138.948610513903;5190.688558686472;5217.295665085222;5217.660852044624;5261.447548007462;5240.764165612557;5328.413874473805;5329.7601607927545;5336.252924590561;5348.866278343053;5148.794048238104;5208.947910520884;5311.5750739941095;5317.175837745156;5359.062164121283;5154.252633368178;5208.453112423217;5238.711870847102;5242.5281724644965;5298;5178.663386879841;5189.2475591039465;5205.427402985706;5208.401033881114;5210.583153347723;5550.333959924835;5648.943510564883;5691.9146212806845;5696.971515142439;5758.395368486333;6109.5112391008615;6144.279601711528;6145.463634091477;6148.415900489491;6150.354212807681;4789.473157952622;6167.722452489649;6195.87608247837;6318.115463835067;6333.809985700436;5785.334686511021;5789.884202316014;5796.985527532749;5797.507211886952;5839.124131380285;6233.376662333731;6238.859180035633;6249.96875015622;6304.117423560705;6311.368863113652;5987.64074155552;5997.280326360865;6085.726142323606;6098.786542470993;6110.419510146529;5199.740012999335;6114.908276375858;6224.221972253468;6227.501799856001;6232.470240029648;6048.546359023091;6060.969695151496;6064.636121832704;6138.386161383915;6143.692815359214;6193.6903154842075;6197.783077592263;6206.472449841814;6217.160683307696;6222.284437289679;4906.9263961040615;4912.287718280829;4918.2376731606655;4931.408231012301;4933.605311575066;4325.870223893288;4352.194843953878;4356.477222733229;4423.1153769246275;4440.777961101932;5766.221560089334;5768.394318596571;5769.884602308014;5770.971145144247;5771.5671324649975;6217.6891155442045;6221.844453888689;6224.097505861625;6227.470664993436;6232.25212974446;5666.858328541737;5742;5744.626599271034;5751.1085781703905;5759.280089988752;5212.452692467312;5365.38298095724;5369.3556773187465;5373.2746079278795;5377.7580008899695;6257.5932564383165;6298.968505157445;6299.307076221704;6307.96846015767;6309.085182648582;6019.608725432833;6180.042093475518;6240.157578726814;6283.937160628518;6299.685015749286;6311;6315.5894866833505;6328.367163283635;6328.620282782864;6335.429811316819;6025.246844144482;6116.296625887901;6180.258368995749;6181.721822518088;6186.288576813544;6105.023196288573;6125.265974675844;6141.416565426277;6211.192544969125;6240.063990401476;5987.191729116687;5999.640021598718;6005.279366476051;6009.7295621698;6026.517878569616;5862.149988251765;5965.135055417027;5986.910196347146;5989.371116032754;5989.880202396015;4564.726116432892;5807.186993820953;5816.41835816415;5829.621074630305;5902.38025007368;4369.994901172811;5897.174395584536;6115.694215289129;6145.723442445012;6146.692665366803;5909.290885093645;5916.26046744157;5920.822375328747;5923.49650279731;5944.64332140073 04:53:18 INFO - PROCESS | 1685 | |1;ext.html;448.18430456569035;460.11657617374635;489.7208591103073;495.26205953129835;501.338233531738;30562.38875222497;38860.97654863497;40822;40885.386719199196;42927.35608965868;42636;42975.92560185997;43007;43056;43115.56884431155;9404.811903761938;9578.712638620853;9603.135717785399;9626.855597166048;9630.518474076269;8592.656293748274;8788.121187881192;11349;11600.941995290012;11617.302961822275;11579.36313502757;11616.477258523344;11623.6512904613;11643.35961522116;11661.883381166206;11551.768964620702;11616.767664646744;11618;11629.651110466699;11644.475998580041;8519.488830670118;8742.12578742127;11169.497372618252;11458.541658333657;11472.94263528677;39147.80426097851;40506;40868;40939;40993.590064099575;4000.337246383277;4026.577209393001;4056.249593825152;4088.7546747195265;4104.630583247522;30245.09264722062;30847.845760771277;31065.980711253695;31261;31326.686733132832;3209.0854106579513;3234.498652708834;3258.1365938026443;3329.9833500832583;3346.4478361070396;445033;446111.38610148814;446744;447432;447814;53836;59405;61631;61901.690491547255;62385;64825.35174648288;64914.35085649083;64939.35060649428;64962.67518662376;64994.67502662551;14927.104373737393;15576.610584735472;15631.296591653403;15677.451289204822;15756.527304180892;10655.413952232702;11692.181547291608;11723.237989530653;11915.285082895054;11958.701032474257;15381.461648842236;15914.124723140107;17655.911720441316;17923.283068677367;18105.275788968556;7195.820104497429;7290.270972902774;7317.817054573572;7326.450516211199;7355.926440735631;836.3309352517962;865.1435079271475;867.9281087856492;872.7556284240422;897.2507955856933;23872.64191037136;29659.55510667342;29706.554401683992;29914.653840577226;30309.545356819224;1016.506994107857;1026.461107918349;1062.219268837404;1068.3269540189785;1169.1640477058943;15169.000831437239;15288.464903728316;15333.84996125295;15565.29956151976;15809;577715.2228477745;580634;580957;581587;581688;59631.40368596345;59676;59680.70159649261;59857.503562411286;59961.70019149876;1215.8237055626885;1377.6624726941964;1395.3651088754593;1408.4366253498479;1411.5835828430431;28429.857850710614;29197.85401072981;29220.123396298146;29879;30058.398832023675;51826.481735183675;52037;52052;52107;52119.47880521146;474173;476215;477589;477866;479021;20471.69292460644;23976.64035039441;38712.41931370975;40436.786896393154;40904.79547602243;35623;36413;36532.63467365292;41222.1755564893;42233.788831056874;30959.535606966365;32401.865934692174;34259.65740342565;35087.47368789419;36934.07664808401;34875.953721388396;35131.64868351284;35302.646973530966;36470;36504.817475913514;29328.680209391056;33322;35082.59669613289;35199.12002199965;35293.47059794157;30716.232094197825;31442.842785785924;31721.5241771369;31744.841275793475;31776;19931.900340498203;22823.771762282166;23138.711305938574;23838.404039899142;24116.397090072187 04:53:18 INFO - PROCESS | 1685 | |2;jquery.html;342.1275746845544;372.33352299384086;375.72384297541294;393.0272575375943;400.64342734965885;18891.811081889176;19758;20803.687944680823;20855.68716469252;20971.47571310718;19729.01354932255;20142.992850357496;20622.072006759696;20715.75327057953;20748.170073197052;4972.881101752113;5236.738163091849;5469.671819690831;5660.773569057233;5717.542596592262;5738.454846789548;5750.849830033989;5767.048437007888;5770.19217309577;5804.448577385142;5182.326297581328;5727.828165155052;5735.340435849889;5763.135529670562;5839.824805255849;5743.224664670258;5861.970690146543;5909.054551271797;5912.3496415394275;5914.733836977346;5517.862053448655;5695.288088988877;5750.309962804448;5764.423557644244;5790.13148027795;12743.107982441234;12778.808317875146;12795.48818047277;13007.934960325232;13014.674633134153;2593.351662084479;2661.7870570354326;2671.679398472176;2673.893044278246;2684.985946010707;9706.223502119814;9893.10962013415;9913.900860991442;10264.846027309597;10371.79256414875;1829.652366050446;1829.7163939589252;1841.2174825699158;1892.4417296897448;1916.1473144450724;84764.16627090235;91973.74406966931;92463.53768231183;102898.9710102897;103456.44815327702;11187.776244475228;11361.488733007034;11445.88554114465;11472.239020806272;11631.59289424883;14421.78367324491;14428.206448645426;14437.639059023608;14446.205458699873;14449.205293708947;8031.357491400674;8182.140875208018;8212;8307.878436411129;8308.750737477885;7641.961790191013;7840.529568225925;7841.372690184771;7957.442978991415;8136.674533018731;4462.152191083684;4521.841735539306;4523.638108951276;4577.359169716243;4662.4405071390975;1875.5498680316625;1919.2898627507911;1920.3470819921295;1925.865189436726;1930.3726288956145;973.73709098543;995.7510622344414;1007.6876168387823;1009.9899001009895;1040.9323393979366;20150.496237593885;20466.692999605017;20533.58932821335;20601.484962875747;20924.953752312627;900.3112618846587;945.7115579748242;990.663174520652;996.8984272379062;1032.2722480651123;10330.276880618434;10714;10788.975047370488;10829.07952824003;10832.783344333227;540264;540900;545712;564537.3546264485;564606;87829.5608521966;119017;128608.35695821648;132448;133051;954.9665761698308;963.9325247232626;973.7468258252763;981.7643765496372;982.0670363155032;17616;18079.63840723152;18095.638087238443;18173.182206800462;18188.72716909221;47318.76340618275;50083.24875126803;50492.24261636005;51851.999980003726;52265;57599.71200143972;73754.89367659383;75259;75629.86555201566;77507;7009.334113259232;7264.455165862476;7729.845403091794;7845.333146682596;8289.544075075928;11614.88385116138;11750.882491174978;11783.057355411718;11957.282563046245;12679.556215532411;10391.896081039093;10607.94696026515;10835.404052777172;11221.382823944761;11328.660140195807;10569.682909512727;10768.892311076788;10814.459277036274;11143.164262680493;11182.496787644413;10830.869426242658;11049.668509944715;11198.88801112011;11386.886131138583;11430.599929002445;7219.819504512429;7249.963750181215;7345.22875098107;7368.189499155196;7529.058867641545;8554.78613034679;10687;11056.949589788957;11126.721831954266;11275.548978040786 04:53:18 INFO - PROCESS | 1685 | |3;mootools.html;1185.4546908422126;1398.5874167120699;1415.837178724447;1416.5608661314993;1426.9714605707886;7956.562389068599;8381.91618083819;8399.412041157122;8399.664013439471;8407.49555026698;7344;7610.467267291292;7640.617969101537;7878.33034192093;8126.91873081269;2260.9095636174534;2305.0664480885184;2306.884655767213;2307.261676263596;2308.4228942764307;2279.0969540433757;2316.328264803205;2319.188284100568;2320.570694421529;2325.2094287942095;2022.6258142243732;2098.0663604695915;2222.944426389337;2274.454131008554;2303.0442366417915;2276.931692049233;2289.4505318723554;2291.5302363015544;2292.5071109711494;2298.2300929188827;2290.1755368067484;2305.4697419593585;2307.976920230793;2316.409315624505;2319.6404557293645;8246.876296855551;8265.876011859827;8369.03756068054;8369.995600527976;8373;4433.689641725081;4444.444444444444;4987.750612469362;5081.4918508148885;5153.716545589991;3313.8177400242976;3464.41105012146;3547.8580856765698;3549.201429678302;3553.1650062235353;1096.6984079378155;1202.188522747151;1210.5339444313915;1235.2341548240058;1239.522783728262;8648.740537783939;10667.359958402521;10712.03591676745;10713.625023124232;10714.03573678365;4253.149370125925;4352.934705979413;4407.515173330931;4408.25059739845;4412.42638457005;8655.56722163902;8662.047174810767;8664.913350866536;8668.913310866938;8682.652693892174;5035.320231768665;5036.420811606639;5057.949420505822;5059.974700126476;5061.468545802712;4578.153041687232;4586.931196032062;4724.787384567703;4754.809807607657;4786.760661966888;3430.6569343065494;3491.2493813830147;3524.2599054198904;3556.4309710446723;3560.394732895414;1080.486768784821;1103.2828661370174;1105.4196546812987;1114.4093630375914;1114.4706264524286;753.4575105923835;771.4484143837184;886.2909672262165;894.4454438248198;939.4598106088889;5442.755076021588;5530.806421775299;5761.164631128547;5817.7963771267805;6029.88447137281;922.6678395777515;937.7139972308375;961.6874515782399;974.4640447753849;1006.1196453103535;3248.8538015789345;3651.5253017107607;3676.584514961759;3702.4631428442726;3759.473673685686;9649.228061755042;9844.261680374;9954.601815927283;9966.80066398668;10024.047715467019;8277.882485864451;9889.703308900744;9900.108990190918;9911.553980070914;9953.402795832273;7195.532290401212;7377.3360397564475;7677.769666910001;7708.38332933364;7765.805854853674;5512.118061110203;5597.6845441322075;5615.6630602164005;5697.373288938131;5764.855878603068;8819.338549608678;8947.552622368985;9025.548722563977;9092.590833412645;9161.908380916373;5748.936446757363;9184.403013804082;9335.113164249386;9406;9427.481488518197;7020.333068358498;7161.57030578167;7184.748533801292;7198.136223652986;7202.135743710788;7624.5425274483705;7693.961530192313;7796.84406311882;7801.804954876174;7824.21757824213;7022.859542809218;7045.330693584101;7092.04257425241;7097.858042839011;7228.674709638184;6932.341427564374;6998.335158159966;7014.438844892498;7034.577925324497;7043.154821421463;6149.96925015372;6294;6315.273743519554;6371.585846919936;6382.20222472191;4095.689379398683;4737.502562231054;4746.715197088186;4783.904321913473;4909.656324057424;4640.211164102043;4719;4790.233562629963;4836.758162091811;4910.754462276944 04:53:18 INFO - PROCESS | 1685 | |4;prototype.html;314.5454817788295;349.63637816670655;349.69751165242076;360.9458581212816;361.8425984696657;4360.825566977325;5156.484351564842;5172.379334465875;5224.660397074187;5340.759665815038;4990.825321113762;5104.566111880495;5161.819336323229;5259.184826351912;5261.8947621047555;3035.4080954213937;3342.46520556711;3685.778853268806;3789.7915614641183;4027.7583344999393;3771.6416940390754;3856.093817952779;4052.29084910141;4053.756774593519;4078.490188726409;4073.4704488416614;4075.5924407559305;4082.81627326771;4083.4079058536495;4098.73358231714;3894.182221733411;4016.3332217129855;4066.755994640316;4118.279301122321;4132.359484279941;2898.6086478655193;3095.845207739604;3280.704121871871;3622.6196249393697;3634.890953271406;3997.960020399817;4280.443542339507;4982.551570358649;5051.419086805027;5091.319864444725;3998.5601583825755;4493;4647.558481944244;4651.883702907455;4664.906701865978;5080.644354895159;5285.947140528622;5432.70120143392;5441.619496305472;5442.333256704186;3965.6232657897453;3969.868587452591;4030.355143177107;4031.294523458412;4033.2135233629456;4950.777215025332;5448.591355648303;5660.377358490604;5734.569907256972;5767.538596912237;3598.7480876338404;3836.0218144373007;3903.512060992376;4061.3501839705505;4112.712110152261;3385.4921761735936;3832.827522761482;3947.8945895149404;3959.089409435788;4133.359329303963;3815.0624019504826;3947.6644485218503;4618.307253911873;4631.698939568917;4658.277966915133;4721.544796705763;5421.132618781055;5434.538064264502;5454.9271276915315;5458.317710286215;1129.6215767717906;1220.3288191494642;1236.164296020409;1240.2744394529302;1246.389269258057;784.5096814490943;837.4891316297054;843.7173546861748;851.9776268477727;856.6273670953155;4326.848560300375;4674.813007479731;4902.926456103161;4905.435874874435;4913.774154227362;4560.407147070926;4814.7833347499445;4950.925736113961;5058.83646761247;5379.310344827586;4047.494063242095;4410;4644.8374306899095;4885.951140488621;4892.461829198785;2538.3727841684135;2542.9872850635998;2694.669111218782;2699.9865000675263;2856.8285902845896;4541.186342476985;5472.781088756406;5534.391216966131;5535.557155427556;5540.944590554042;4621.798332433592;4895.534924182197;5002.474740152305;5369.946300537023;5373.435789242151;4164.312888373384;4513.593776559994;4871.780769865364;5315.920261196085;5349.652272602269;4826.975865120792;5180.503160366813;5222.3733152022005;5277.604179686462;5310.654807437504;4488.394066801071;4700.730802683324;5157.421289355322;5213.687178769286;5220.608454365862;5433.483819037185;5523.536262890257;5633.295838020247;5684.857878553069;5909.438603332676;3345.799252044885;3735.7384983050924;3746.756460830037;3815.389537673959;3829.5021647185686;5657.717114144359;5660.688662123623;5708.743106560298;5713.971430142823;5730.3410107838135;5294.70279781456;5396.354111995879;5426.07756681373;5452.57360474585;5470.316210473691;4343.543927887653;4658.534146585314;5245.423003469539;5415.431379705232;5577.553795696415;4336.934945975876;4825.9758701206265;5374.838754837361;5404.83785486436;5410.648307859977;4623.352730617649;5090.8291093048065;5216.295800066942;5430.8099216527235;5547.805826796042;4836.5973867578;4977.552020318044;5231.985685511182;5266.733945504154;5343.732813359395 04:53:18 INFO - PROCESS | 1685 | |5;yui.html;704.6822442033392;728.806866180462;734.1153909539006;740.896274521567;754.3211110001;12064.2761434314;15575.532734017983;15578.143202123878;15586.84413155868;15596.298166582503;13977.371018304175;14212.218327991955;14218.573442796733;14227.146371217708;14272.500462483815;687.5153017122941;718.317598281632;873.4104479476359;875.1423604867234;875.6497401039603;695.9826004349881;705.9869087858913;855.9914400856005;872.4372779557182;898.27239935652;944.0795224655974;948.8624149498319;949.1552518258756;950.2445555783135;950.9286803489731;896.6906417286026;915.1809130827938;935.1863878425745;935.223764275653;939.3377668743519;529.2352550564436;549.9945000550023;661.2032500836528;680.6426626021329;683.7572661705073;14904.552863414116;14907.478238261718;14943.476978305707;14951.177685227309;14973.401063957428;13729.656758581015;13776.793348099787;13813.51652692151;13832.723345533133;13852.445902163901;12663.30351830649;12714.249296457567;12826.551070712574;12920.095593308472;13272.867271327259;4385.079133381962;4410.184115938561;4431.446069241345;4512.210363186429;4654.930176047362;102797;103524;104311.4784426073;104698.47650761774;105467.47266263697;9140.999999999867;10489.318194317346;10834.241603087708;10905.236633435583;11254.099672026217;20909.163633454795;21117.8385188813;21162.365129046153;21217.257395991375;21218.57562848765;7897.842043159105;8006.15935326794;8129.187081291823;8170.836583268301;8419;4299.935500967488;4350.543192964693;4380.8685739427865;4412.977935110304;4508.346289788028;3139.21519620095;3589.353916295092;3654.908627284339;3696.6488183622514;3703.925921481555;649.9187601549806;651.4202359899693;663.2770280394296;669.5982410553629;671.993280067193;611.4832966143601;641.3330136657917;642.2998931164959;653.3564439027554;681.5774219983542;8775.385722999456;9233.630654773733;9234.028164531746;9485.098915603006;9519.23846092311;627.5199472403615;651.0299653516226;666.9866602668017;686.9553479023848;691.4917535611322;4270.765107919095;4414.3157810539415;4416.84541041062;4430.977845110754;4458.576435238647;99783;102330;105154;106535.33661658365;109976;70024;76337.23662763301;78662;85835.57082214548;87800.56099719461;81210.59394703107;82576;82817.58591207005;83379.5831020841;84778.15221847827;8645.654173832976;8651.351148664002;8656.350773691873;8666.393352465268;8772.29821614282;52937;54825.177622336494;55173.44826551683;55425;56252.43747562636;52495.47504525058;57842.7107864458;71848.28151718416;72572.28280161772;74229.62885185756;11857.525698971944;11900.047996160114;11909.106816988933;12061.75876482483;12111.183656381452;12297.50809967627;12606.306653133799;12678.429470673658;12981.479095854786;13012.479500819862;11743.014749970102;11929.138158744043;12076.275423474619;12080.39598020113;12482.625521234377;11745;11804.291742495478;11960.880391195977;12096.697582560506;12605.74788504243;11591.478383472933;11834.881651183377;12088.576899808464;12142.210756300812;12379.814302785646;8078.87881681787;9477.383970041925;10091.495425228562;10162.18702503783;10653.680389588324;9627.454195799006;9639;9743.31796774219;9746.171575415885;9775.916895280621 04:53:18 INFO - PROCESS | 1685 | __end_tp_report 04:53:18 INFO - PROCESS | 1685 | __start_cc_report 04:53:18 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1371 04:53:18 INFO - PROCESS | 1685 | __end_cc_report 04:53:18 INFO - PROCESS | 1685 | __startTimestamp1482843198944__endTimestamp 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | ------- Summary: start ------- 04:53:18 INFO - PROCESS | 1685 | Number of tests: 6 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | [#0] dojo.html Cycles:165 Average:5681.29 Median:5862.15 stddev:661.28 (11.3%) stddev-sans-first:620.56 04:53:18 INFO - PROCESS | 1685 | Values: 2699.4 3335.9 3346.9 3353.8 3409.4 5496.9 5608.9 5668.9 5731.2 5741.6 4681.3 5662.2 5664.6 5722.3 5737.7 5138.9 5190.7 5217.3 5217.7 5261.4 5240.8 5328.4 5329.8 5336.3 5348.9 5148.8 5208.9 5311.6 5317.2 5359.1 5154.3 5208.5 5238.7 5242.5 5298.0 5178.7 5189.2 5205.4 5208.4 5210.6 5550.3 5648.9 5691.9 5697.0 5758.4 6109.5 6144.3 6145.5 6148.4 6150.4 4789.5 6167.7 6195.9 6318.1 6333.8 5785.3 5789.9 5797.0 5797.5 5839.1 6233.4 6238.9 6250.0 6304.1 6311.4 5987.6 5997.3 6085.7 6098.8 6110.4 5199.7 6114.9 6224.2 6227.5 6232.5 6048.5 6061.0 6064.6 6138.4 6143.7 6193.7 6197.8 6206.5 6217.2 6222.3 4906.9 4912.3 4918.2 4931.4 4933.6 4325.9 4352.2 4356.5 4423.1 4440.8 5766.2 5768.4 5769.9 5771.0 5771.6 6217.7 6221.8 6224.1 6227.5 6232.3 5666.9 5742.0 5744.6 5751.1 5759.3 5212.5 5365.4 5369.4 5373.3 5377.8 6257.6 6299.0 6299.3 6308.0 6309.1 6019.6 6180.0 6240.2 6283.9 6299.7 6311.0 6315.6 6328.4 6328.6 6335.4 6025.2 6116.3 6180.3 6181.7 6186.3 6105.0 6125.3 6141.4 6211.2 6240.1 5987.2 5999.6 6005.3 6009.7 6026.5 5862.1 5965.1 5986.9 5989.4 5989.9 4564.7 5807.2 5816.4 5829.6 5902.4 4370.0 5897.2 6115.7 6145.7 6146.7 5909.3 5916.3 5920.8 5923.5 5944.6 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | [#1] ext.html Cycles:180 Average:64225.44 Median:35462.82 stddev:134384.22 (378.9%) stddev-sans-first:134675.89 04:53:18 INFO - PROCESS | 1685 | Values: 448.2 460.1 489.7 495.3 501.3 30562.4 38861.0 40822.0 40885.4 42927.4 42636.0 42975.9 43007.0 43056.0 43115.6 9404.8 9578.7 9603.1 9626.9 9630.5 8592.7 8788.1 11349.0 11600.9 11617.3 11579.4 11616.5 11623.7 11643.4 11661.9 11551.8 11616.8 11618.0 11629.7 11644.5 8519.5 8742.1 11169.5 11458.5 11472.9 39147.8 40506.0 40868.0 40939.0 40993.6 4000.3 4026.6 4056.2 4088.8 4104.6 30245.1 30847.8 31066.0 31261.0 31326.7 3209.1 3234.5 3258.1 3330.0 3346.4 445033.0 446111.4 446744.0 447432.0 447814.0 53836.0 59405.0 61631.0 61901.7 62385.0 64825.4 64914.4 64939.4 64962.7 64994.7 14927.1 15576.6 15631.3 15677.5 15756.5 10655.4 11692.2 11723.2 11915.3 11958.7 15381.5 15914.1 17655.9 17923.3 18105.3 7195.8 7290.3 7317.8 7326.5 7355.9 836.3 865.1 867.9 872.8 897.3 23872.6 29659.6 29706.6 29914.7 30309.5 1016.5 1026.5 1062.2 1068.3 1169.2 15169.0 15288.5 15333.8 15565.3 15809.0 577715.2 580634.0 580957.0 581587.0 581688.0 59631.4 59676.0 59680.7 59857.5 59961.7 1215.8 1377.7 1395.4 1408.4 1411.6 28429.9 29197.9 29220.1 29879.0 30058.4 51826.5 52037.0 52052.0 52107.0 52119.5 474173.0 476215.0 477589.0 477866.0 479021.0 20471.7 23976.6 38712.4 40436.8 40904.8 35623.0 36413.0 36532.6 41222.2 42233.8 30959.5 32401.9 34259.7 35087.5 36934.1 34876.0 35131.6 35302.6 36470.0 36504.8 29328.7 33322.0 35082.6 35199.1 35293.5 30716.2 31442.8 31721.5 31744.8 31776.0 19931.9 22823.8 23138.7 23838.4 24116.4 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | [#2] jquery.html Cycles:180 Average:32131.84 Median:1513.56 stddev:91806.11 (6065.6%) stddev-sans-first:92032.62 04:53:18 INFO - PROCESS | 1685 | Values: 342.1 372.3 375.7 393.0 400.6 18891.8 19758.0 20803.7 20855.7 20971.5 19729.0 20143.0 20622.1 20715.8 20748.2 4972.9 5236.7 5469.7 5660.8 5717.5 5738.5 5750.8 5767.0 5770.2 5804.4 5182.3 5727.8 5735.3 5763.1 5839.8 5743.2 5862.0 5909.1 5912.3 5914.7 5517.9 5695.3 5750.3 5764.4 5790.1 12743.1 12778.8 12795.5 13007.9 13014.7 2593.4 2661.8 2671.7 2673.9 2685.0 9706.2 9893.1 9913.9 10264.8 10371.8 1829.7 1829.7 1841.2 1892.4 1916.1 84764.2 91973.7 92463.5 102899.0 103456.4 11187.8 11361.5 11445.9 11472.2 11631.6 14421.8 14428.2 14437.6 14446.2 14449.2 8031.4 8182.1 8212.0 8307.9 8308.8 7642.0 7840.5 7841.4 7957.4 8136.7 4462.2 4521.8 4523.6 4577.4 4662.4 1875.5 1919.3 1920.3 1925.9 1930.4 973.7 995.8 1007.7 1010.0 1040.9 20150.5 20466.7 20533.6 20601.5 20925.0 900.3 945.7 990.7 996.9 1032.3 10330.3 10714.0 10789.0 10829.1 10832.8 540264.0 540900.0 545712.0 564537.4 564606.0 87829.6 119017.0 128608.4 132448.0 133051.0 955.0 963.9 973.7 981.8 982.1 17616.0 18079.6 18095.6 18173.2 18188.7 47318.8 50083.2 50492.2 51852.0 52265.0 57599.7 73754.9 75259.0 75629.9 77507.0 7009.3 7264.5 7729.8 7845.3 8289.5 11614.9 11750.9 11783.1 11957.3 12679.6 10391.9 10607.9 10835.4 11221.4 11328.7 10569.7 10768.9 10814.5 11143.2 11182.5 10830.9 11049.7 11198.9 11386.9 11430.6 7219.8 7250.0 7345.2 7368.2 7529.1 8554.8 10687.0 11056.9 11126.7 11275.5 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | [#3] mootools.html Cycles:180 Average:5290.43 Median:5298.24 stddev:2874.96 (54.3%) stddev-sans-first:2866.47 04:53:18 INFO - PROCESS | 1685 | Values: 1185.5 1398.6 1415.8 1416.6 1427.0 7956.6 8381.9 8399.4 8399.7 8407.5 7344.0 7610.5 7640.6 7878.3 8126.9 2260.9 2305.1 2306.9 2307.3 2308.4 2279.1 2316.3 2319.2 2320.6 2325.2 2022.6 2098.1 2222.9 2274.5 2303.0 2276.9 2289.5 2291.5 2292.5 2298.2 2290.2 2305.5 2308.0 2316.4 2319.6 8246.9 8265.9 8369.0 8370.0 8373.0 4433.7 4444.4 4987.8 5081.5 5153.7 3313.8 3464.4 3547.9 3549.2 3553.2 1096.7 1202.2 1210.5 1235.2 1239.5 8648.7 10667.4 10712.0 10713.6 10714.0 4253.1 4352.9 4407.5 4408.3 4412.4 8655.6 8662.0 8664.9 8668.9 8682.7 5035.3 5036.4 5057.9 5060.0 5061.5 4578.2 4586.9 4724.8 4754.8 4786.8 3430.7 3491.2 3524.3 3556.4 3560.4 1080.5 1103.3 1105.4 1114.4 1114.5 753.5 771.4 886.3 894.4 939.5 5442.8 5530.8 5761.2 5817.8 6029.9 922.7 937.7 961.7 974.5 1006.1 3248.9 3651.5 3676.6 3702.5 3759.5 9649.2 9844.3 9954.6 9966.8 10024.0 8277.9 9889.7 9900.1 9911.6 9953.4 7195.5 7377.3 7677.8 7708.4 7765.8 5512.1 5597.7 5615.7 5697.4 5764.9 8819.3 8947.6 9025.5 9092.6 9161.9 5748.9 9184.4 9335.1 9406.0 9427.5 7020.3 7161.6 7184.7 7198.1 7202.1 7624.5 7694.0 7796.8 7801.8 7824.2 7022.9 7045.3 7092.0 7097.9 7228.7 6932.3 6998.3 7014.4 7034.6 7043.2 6150.0 6294.0 6315.3 6371.6 6382.2 4095.7 4737.5 4746.7 4783.9 4909.7 4640.2 4719.0 4790.2 4836.8 4910.8 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | [#4] prototype.html Cycles:180 Average:4337.14 Median:4820.38 stddev:1307.37 (27.1%) stddev-sans-first:1275.70 04:53:18 INFO - PROCESS | 1685 | Values: 314.5 349.6 349.7 360.9 361.8 4360.8 5156.5 5172.4 5224.7 5340.8 4990.8 5104.6 5161.8 5259.2 5261.9 3035.4 3342.5 3685.8 3789.8 4027.8 3771.6 3856.1 4052.3 4053.8 4078.5 4073.5 4075.6 4082.8 4083.4 4098.7 3894.2 4016.3 4066.8 4118.3 4132.4 2898.6 3095.8 3280.7 3622.6 3634.9 3998.0 4280.4 4982.6 5051.4 5091.3 3998.6 4493.0 4647.6 4651.9 4664.9 5080.6 5285.9 5432.7 5441.6 5442.3 3965.6 3969.9 4030.4 4031.3 4033.2 4950.8 5448.6 5660.4 5734.6 5767.5 3598.7 3836.0 3903.5 4061.4 4112.7 3385.5 3832.8 3947.9 3959.1 4133.4 3815.1 3947.7 4618.3 4631.7 4658.3 4721.5 5421.1 5434.5 5454.9 5458.3 1129.6 1220.3 1236.2 1240.3 1246.4 784.5 837.5 843.7 852.0 856.6 4326.8 4674.8 4902.9 4905.4 4913.8 4560.4 4814.8 4950.9 5058.8 5379.3 4047.5 4410.0 4644.8 4886.0 4892.5 2538.4 2543.0 2694.7 2700.0 2856.8 4541.2 5472.8 5534.4 5535.6 5540.9 4621.8 4895.5 5002.5 5369.9 5373.4 4164.3 4513.6 4871.8 5315.9 5349.7 4827.0 5180.5 5222.4 5277.6 5310.7 4488.4 4700.7 5157.4 5213.7 5220.6 5433.5 5523.5 5633.3 5684.9 5909.4 3345.8 3735.7 3746.8 3815.4 3829.5 5657.7 5660.7 5708.7 5714.0 5730.3 5294.7 5396.4 5426.1 5452.6 5470.3 4343.5 4658.5 5245.4 5415.4 5577.6 4336.9 4826.0 5374.8 5404.8 5410.6 4623.4 5090.8 5216.3 5430.8 5547.8 4836.6 4977.6 5232.0 5266.7 5343.7 04:53:18 INFO - PROCESS | 1685 | 04:53:18 INFO - PROCESS | 1685 | [#5] yui.html Cycles:180 Average:20125.82 Median:28575.20 stddev:29310.39 (102.6%) stddev-sans-first:29356.33 04:53:18 INFO - PROCESS | 1685 | Values: 704.7 728.8 734.1 740.9 754.3 12064.3 15575.5 15578.1 15586.8 15596.3 13977.4 14212.2 14218.6 14227.1 14272.5 687.5 718.3 873.4 875.1 875.6 696.0 706.0 856.0 872.4 898.3 944.1 948.9 949.2 950.2 950.9 896.7 915.2 935.2 935.2 939.3 529.2 550.0 661.2 680.6 683.8 14904.6 14907.5 14943.5 14951.2 14973.4 13729.7 13776.8 13813.5 13832.7 13852.4 12663.3 12714.2 12826.6 12920.1 13272.9 4385.1 4410.2 4431.4 4512.2 4654.9 102797.0 103524.0 104311.5 104698.5 105467.5 9141.0 10489.3 10834.2 10905.2 11254.1 20909.2 21117.8 21162.4 21217.3 21218.6 7897.8 8006.2 8129.2 8170.8 8419.0 4299.9 4350.5 4380.9 4413.0 4508.3 3139.2 3589.4 3654.9 3696.6 3703.9 649.9 651.4 663.3 669.6 672.0 611.5 641.3 642.3 653.4 681.6 8775.4 9233.6 9234.0 9485.1 9519.2 627.5 651.0 667.0 687.0 691.5 4270.8 4414.3 4416.8 4431.0 4458.6 99783.0 102330.0 105154.0 106535.3 109976.0 70024.0 76337.2 78662.0 85835.6 87800.6 81210.6 82576.0 82817.6 83379.6 84778.2 8645.7 8651.4 8656.4 8666.4 8772.3 52937.0 54825.2 55173.4 55425.0 56252.4 52495.5 57842.7 71848.3 72572.3 74229.6 11857.5 11900.0 11909.1 12061.8 12111.2 12297.5 12606.3 12678.4 12981.5 13012.5 11743.0 11929.1 12076.3 12080.4 12482.6 11745.0 11804.3 11960.9 12096.7 12605.7 11591.5 11834.9 12088.6 12142.2 12379.8 8078.9 9477.4 10091.5 10162.2 10653.7 9627.5 9639.0 9743.3 9746.2 9775.9 04:53:18 INFO - PROCESS | 1685 | -------- Summary: end -------- 04:53:18 INFO - PROCESS | 1685 | 04:53:19 INFO - TEST-INFO | 1685: exit 0 04:53:19 INFO - TEST-OK | dromaeo_css | took 1119854ms 04:53:19 INFO - TEST-START | kraken 04:53:19 INFO - operating with platform_type : mac_ 04:53:19 INFO - Initialising browser for kraken test... 04:53:19 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpB4VnHr/profile http://localhost:49240/getInfo.html) 04:53:20 INFO - PROCESS | 1690 | 1482843200168 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm: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 04:53:22 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 04:53:22 INFO - PROCESS | 1690 | colorDepth:24 04:53:22 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 04:53:22 INFO - PROCESS | 1690 | __metrics 04:53:22 INFO - PROCESS | 1690 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:53:22 INFO - PROCESS | 1690 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:53:23 INFO - TEST-INFO | 1690: exit 0 04:53:23 INFO - Browser initialized. 04:53:23 INFO - Running cycle 1/1 for kraken test... 04:53:23 INFO - TEST-INFO | started process 1692 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpB4VnHr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:53:35 INFO - PROCESS | 1692 | RSS: Main: 233201664 04:53:35 INFO - PROCESS | 1692 | 04:53:37 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 04:53:38 INFO - PROCESS | 1692 | RSS: Main: 287346688 04:53:38 INFO - PROCESS | 1692 | 04:53:41 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 04:53:41 INFO - PROCESS | 1692 | RSS: Main: 289542144 04:53:41 INFO - PROCESS | 1692 | 04:53:45 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 04:53:45 INFO - PROCESS | 1692 | RSS: Main: 379539456 04:53:45 INFO - PROCESS | 1692 | 04:53:47 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 04:53:48 INFO - PROCESS | 1692 | RSS: Main: 283656192 04:53:48 INFO - PROCESS | 1692 | 04:53:50 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:53:51 INFO - PROCESS | 1692 | RSS: Main: 286965760 04:53:51 INFO - PROCESS | 1692 | 04:53:53 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 04:53:54 INFO - PROCESS | 1692 | RSS: Main: 330264576 04:53:54 INFO - PROCESS | 1692 | 04:53:57 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 04:53:58 INFO - PROCESS | 1692 | RSS: Main: 328904704 04:53:58 INFO - PROCESS | 1692 | 04:54:00 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 04:54:01 INFO - PROCESS | 1692 | RSS: Main: 326942720 04:54:01 INFO - PROCESS | 1692 | 04:54:03 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:54:03 INFO - PROCESS | 1692 | RSS: Main: 303882240 04:54:03 INFO - PROCESS | 1692 | 04:54:05 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:54:06 INFO - PROCESS | 1692 | RSS: Main: 309354496 04:54:06 INFO - PROCESS | 1692 | 04:54:08 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:54:09 INFO - PROCESS | 1692 | RSS: Main: 310980608 04:54:09 INFO - PROCESS | 1692 | 04:54:12 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:54:12 INFO - PROCESS | 1692 | RSS: Main: 325648384 04:54:12 INFO - PROCESS | 1692 | 04:54:15 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:54:16 INFO - PROCESS | 1692 | RSS: Main: 318844928 04:54:16 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 04:54:18 INFO - PROCESS | 1692 | RSS: Main: 363081728 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | __start_tp_report 04:54:18 INFO - PROCESS | 1692 | _x_x_mozilla_page_load 04:54:18 INFO - PROCESS | 1692 | _x_x_mozilla_page_load_details 04:54:18 INFO - PROCESS | 1692 | |i|pagename|runs| 04:54:18 INFO - PROCESS | 1692 | |0;ai-astar;114;115;114;115;114;114;113;114;114;113 04:54:18 INFO - PROCESS | 1692 | |1;audio-beat-detection;138;114;112;147;112;109;110;111;132;115 04:54:18 INFO - PROCESS | 1692 | |2;audio-dft;185;184;185;184;184;183;180;186;183;185 04:54:18 INFO - PROCESS | 1692 | |3;audio-fft;79;59;59;88;60;62;60;58;75;57 04:54:18 INFO - PROCESS | 1692 | |4;audio-oscillator;91;70;88;70;69;69;84;69;92;115 04:54:18 INFO - PROCESS | 1692 | |5;imaging-gaussian-blur;91;87;90;87;89;87;92;87;90;86 04:54:18 INFO - PROCESS | 1692 | |6;imaging-darkroom;139;93;95;95;94;94;95;95;95;101 04:54:18 INFO - PROCESS | 1692 | |7;imaging-desaturate;87;88;84;85;87;87;86;87;88;86 04:54:18 INFO - PROCESS | 1692 | |8;json-parse-financial;57;57;58;55;55;57;57;55;56;55 04:54:18 INFO - PROCESS | 1692 | |9;json-stringify-tinderbox;66;70;75;77;75;71;70;72;68;75 04:54:18 INFO - PROCESS | 1692 | |10;stanford-crypto-aes;90;86;86;91;84;89;86;88;88;92 04:54:18 INFO - PROCESS | 1692 | |11;stanford-crypto-ccm;141;139;140;139;139;141;144;134;205;179 04:54:18 INFO - PROCESS | 1692 | |12;stanford-crypto-pbkdf2;178;174;172;172;175;171;176;172;172;174 04:54:18 INFO - PROCESS | 1692 | |13;stanford-crypto-sha256-iterative;69;69;68;68;67;68;67;64;66;66 04:54:18 INFO - PROCESS | 1692 | __end_tp_report 04:54:18 INFO - PROCESS | 1692 | __start_cc_report 04:54:18 INFO - PROCESS | 1692 | _x_x_mozilla_cycle_collect,2033 04:54:18 INFO - PROCESS | 1692 | __end_cc_report 04:54:18 INFO - PROCESS | 1692 | __startTimestamp1482843258478__endTimestamp 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | ------- Summary: start ------- 04:54:18 INFO - PROCESS | 1692 | Number of tests: 14 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 04:54:18 INFO - PROCESS | 1692 | Values: 114.0 115.0 114.0 115.0 114.0 114.0 113.0 114.0 114.0 113.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:114.50 stddev:13.70 (12.0%) stddev-sans-first:12.88 04:54:18 INFO - PROCESS | 1692 | Values: 138.0 114.0 112.0 147.0 112.0 109.0 110.0 111.0 132.0 115.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.66 (0.9%) stddev-sans-first:1.72 04:54:18 INFO - PROCESS | 1692 | Values: 185.0 184.0 185.0 184.0 184.0 183.0 180.0 186.0 183.0 185.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#3] audio-fft Cycles:10 Average:65.70 Median:61.00 stddev:10.87 (17.8%) stddev-sans-first:10.41 04:54:18 INFO - PROCESS | 1692 | Values: 79.0 59.0 59.0 88.0 60.0 62.0 60.0 58.0 75.0 57.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:77.00 stddev:15.29 (19.9%) stddev-sans-first:15.84 04:54:18 INFO - PROCESS | 1692 | Values: 91.0 70.0 88.0 70.0 69.0 69.0 84.0 69.0 92.0 115.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.50 stddev:2.07 (2.3%) stddev-sans-first:2.00 04:54:18 INFO - PROCESS | 1692 | Values: 91.0 87.0 90.0 87.0 89.0 87.0 92.0 87.0 90.0 86.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:95.00 stddev:14.01 (14.7%) stddev-sans-first:2.28 04:54:18 INFO - PROCESS | 1692 | Values: 139.0 93.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 101.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:87.00 stddev:1.27 (1.5%) stddev-sans-first:1.33 04:54:18 INFO - PROCESS | 1692 | Values: 87.0 88.0 84.0 85.0 87.0 87.0 86.0 87.0 88.0 86.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.14 (2.0%) stddev-sans-first:1.17 04:54:18 INFO - PROCESS | 1692 | Values: 57.0 57.0 58.0 55.0 55.0 57.0 57.0 55.0 56.0 55.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:73.50 stddev:3.54 (4.8%) stddev-sans-first:3.05 04:54:18 INFO - PROCESS | 1692 | Values: 66.0 70.0 75.0 77.0 75.0 71.0 70.0 72.0 68.0 75.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:88.50 stddev:2.54 (2.9%) stddev-sans-first:2.59 04:54:18 INFO - PROCESS | 1692 | Values: 90.0 86.0 86.0 91.0 84.0 89.0 86.0 88.0 88.0 92.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:141.00 stddev:23.05 (16.3%) stddev-sans-first:24.21 04:54:18 INFO - PROCESS | 1692 | Values: 141.0 139.0 140.0 139.0 139.0 141.0 144.0 134.0 205.0 179.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.00 stddev:2.22 (1.3%) stddev-sans-first:1.69 04:54:18 INFO - PROCESS | 1692 | Values: 178.0 174.0 172.0 172.0 175.0 171.0 176.0 172.0 172.0 174.0 04:54:18 INFO - PROCESS | 1692 | 04:54:18 INFO - PROCESS | 1692 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:68.00 stddev:1.55 (2.3%) stddev-sans-first:1.50 04:54:18 INFO - PROCESS | 1692 | Values: 69.0 69.0 68.0 68.0 67.0 68.0 67.0 64.0 66.0 66.0 04:54:18 INFO - PROCESS | 1692 | -------- Summary: end -------- 04:54:18 INFO - PROCESS | 1692 | 04:54:19 INFO - TEST-INFO | 1692: exit 0 04:54:19 INFO - TEST-OK | kraken | took 59749ms 04:54:19 INFO - SUITE-END | took 1179s 04:54:19 INFO - Completed test suite (00:19:39) 04:54:19 INFO - javascript benchmark 04:54:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2699.419624780672, 3335.8665653373882, 3346.928982725528, 3353.8323083845803, 3409.4203985322492, 5496.945030549703, 5608.887822243552, 5668.886622267557, 5731.226284451598, 5741.569382296324, 4681.297805329203, 5662.207290979277, 5664.631798933066, 5722.313322401307, 5737.713114344287, 5138.948610513903, 5190.688558686472, 5217.295665085222, 5217.660852044624, 5261.447548007462, 5240.764165612557, 5328.413874473805, 5329.7601607927545, 5336.252924590561, 5348.866278343053, 5148.794048238104, 5208.947910520884, 5311.5750739941095, 5317.175837745156, 5359.062164121283, 5154.252633368178, 5208.453112423217, 5238.711870847102, 5242.5281724644965, 5298.0, 5178.663386879841, 5189.2475591039465, 5205.427402985706, 5208.401033881114, 5210.583153347723, 5550.333959924835, 5648.943510564883, 5691.9146212806845, 5696.971515142439, 5758.395368486333, 6109.5112391008615, 6144.279601711528, 6145.463634091477, 6148.415900489491, 6150.354212807681, 4789.473157952622, 6167.722452489649, 6195.87608247837, 6318.115463835067, 6333.809985700436, 5785.334686511021, 5789.884202316014, 5796.985527532749, 5797.507211886952, 5839.124131380285, 6233.376662333731, 6238.859180035633, 6249.96875015622, 6304.117423560705, 6311.368863113652, 5987.64074155552, 5997.280326360865, 6085.726142323606, 6098.786542470993, 6110.419510146529, 5199.740012999335, 6114.908276375858, 6224.221972253468, 6227.501799856001, 6232.470240029648, 6048.546359023091, 6060.969695151496, 6064.636121832704, 6138.386161383915, 6143.692815359214, 6193.6903154842075, 6197.783077592263, 6206.472449841814, 6217.160683307696, 6222.284437289679, 4906.9263961040615, 4912.287718280829, 4918.2376731606655, 4931.408231012301, 4933.605311575066, 4325.870223893288, 4352.194843953878, 4356.477222733229, 4423.1153769246275, 4440.777961101932, 5766.221560089334, 5768.394318596571, 5769.884602308014, 5770.971145144247, 5771.5671324649975, 6217.6891155442045, 6221.844453888689, 6224.097505861625, 6227.470664993436, 6232.25212974446, 5666.858328541737, 5742.0, 5744.626599271034, 5751.1085781703905, 5759.280089988752, 5212.452692467312, 5365.38298095724, 5369.3556773187465, 5373.2746079278795, 5377.7580008899695, 6257.5932564383165, 6298.968505157445, 6299.307076221704, 6307.96846015767, 6309.085182648582, 6019.608725432833, 6180.042093475518, 6240.157578726814, 6283.937160628518, 6299.685015749286, 6311.0, 6315.5894866833505, 6328.367163283635, 6328.620282782864, 6335.429811316819, 6025.246844144482, 6116.296625887901, 6180.258368995749, 6181.721822518088, 6186.288576813544, 6105.023196288573, 6125.265974675844, 6141.416565426277, 6211.192544969125, 6240.063990401476, 5987.191729116687, 5999.640021598718, 6005.279366476051, 6009.7295621698, 6026.517878569616, 5862.149988251765, 5965.135055417027, 5986.910196347146, 5989.371116032754, 5989.880202396015, 4564.726116432892, 5807.186993820953, 5816.41835816415, 5829.621074630305, 5902.38025007368, 4369.994901172811, 5897.174395584536, 6115.694215289129, 6145.723442445012, 6146.692665366803, 5909.290885093645, 5916.26046744157, 5920.822375328747, 5923.49650279731, 5944.64332140073], "value": 5639.7345572317545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.18430456569035, 460.11657617374635, 489.7208591103073, 495.26205953129835, 501.338233531738, 30562.38875222497, 38860.97654863497, 40822.0, 40885.386719199196, 42927.35608965868, 42636.0, 42975.92560185997, 43007.0, 43056.0, 43115.56884431155, 9404.811903761938, 9578.712638620853, 9603.135717785399, 9626.855597166048, 9630.518474076269, 8592.656293748274, 8788.121187881192, 11349.0, 11600.941995290012, 11617.302961822275, 11579.36313502757, 11616.477258523344, 11623.6512904613, 11643.35961522116, 11661.883381166206, 11551.768964620702, 11616.767664646744, 11618.0, 11629.651110466699, 11644.475998580041, 8519.488830670118, 8742.12578742127, 11169.497372618252, 11458.541658333657, 11472.94263528677, 39147.80426097851, 40506.0, 40868.0, 40939.0, 40993.590064099575, 4000.337246383277, 4026.577209393001, 4056.249593825152, 4088.7546747195265, 4104.630583247522, 30245.09264722062, 30847.845760771277, 31065.980711253695, 31261.0, 31326.686733132832, 3209.0854106579513, 3234.498652708834, 3258.1365938026443, 3329.9833500832583, 3346.4478361070396, 445033.0, 446111.38610148814, 446744.0, 447432.0, 447814.0, 53836.0, 59405.0, 61631.0, 61901.690491547255, 62385.0, 64825.35174648288, 64914.35085649083, 64939.35060649428, 64962.67518662376, 64994.67502662551, 14927.104373737393, 15576.610584735472, 15631.296591653403, 15677.451289204822, 15756.527304180892, 10655.413952232702, 11692.181547291608, 11723.237989530653, 11915.285082895054, 11958.701032474257, 15381.461648842236, 15914.124723140107, 17655.911720441316, 17923.283068677367, 18105.275788968556, 7195.820104497429, 7290.270972902774, 7317.817054573572, 7326.450516211199, 7355.926440735631, 836.3309352517962, 865.1435079271475, 867.9281087856492, 872.7556284240422, 897.2507955856933, 23872.64191037136, 29659.55510667342, 29706.554401683992, 29914.653840577226, 30309.545356819224, 1016.506994107857, 1026.461107918349, 1062.219268837404, 1068.3269540189785, 1169.1640477058943, 15169.000831437239, 15288.464903728316, 15333.84996125295, 15565.29956151976, 15809.0, 577715.2228477745, 580634.0, 580957.0, 581587.0, 581688.0, 59631.40368596345, 59676.0, 59680.70159649261, 59857.503562411286, 59961.70019149876, 1215.8237055626885, 1377.6624726941964, 1395.3651088754593, 1408.4366253498479, 1411.5835828430431, 28429.857850710614, 29197.85401072981, 29220.123396298146, 29879.0, 30058.398832023675, 51826.481735183675, 52037.0, 52052.0, 52107.0, 52119.47880521146, 474173.0, 476215.0, 477589.0, 477866.0, 479021.0, 20471.69292460644, 23976.64035039441, 38712.41931370975, 40436.786896393154, 40904.79547602243, 35623.0, 36413.0, 36532.63467365292, 41222.1755564893, 42233.788831056874, 30959.535606966365, 32401.865934692174, 34259.65740342565, 35087.47368789419, 36934.07664808401, 34875.953721388396, 35131.64868351284, 35302.646973530966, 36470.0, 36504.817475913514, 29328.680209391056, 33322.0, 35082.59669613289, 35199.12002199965, 35293.47059794157, 30716.232094197825, 31442.842785785924, 31721.5241771369, 31744.841275793475, 31776.0, 19931.900340498203, 22823.771762282166, 23138.711305938574, 23838.404039899142, 24116.397090072187], "value": 19660.81325635402, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.1275746845544, 372.33352299384086, 375.72384297541294, 393.0272575375943, 400.64342734965885, 18891.811081889176, 19758.0, 20803.687944680823, 20855.68716469252, 20971.47571310718, 19729.01354932255, 20142.992850357496, 20622.072006759696, 20715.75327057953, 20748.170073197052, 4972.881101752113, 5236.738163091849, 5469.671819690831, 5660.773569057233, 5717.542596592262, 5738.454846789548, 5750.849830033989, 5767.048437007888, 5770.19217309577, 5804.448577385142, 5182.326297581328, 5727.828165155052, 5735.340435849889, 5763.135529670562, 5839.824805255849, 5743.224664670258, 5861.970690146543, 5909.054551271797, 5912.3496415394275, 5914.733836977346, 5517.862053448655, 5695.288088988877, 5750.309962804448, 5764.423557644244, 5790.13148027795, 12743.107982441234, 12778.808317875146, 12795.48818047277, 13007.934960325232, 13014.674633134153, 2593.351662084479, 2661.7870570354326, 2671.679398472176, 2673.893044278246, 2684.985946010707, 9706.223502119814, 9893.10962013415, 9913.900860991442, 10264.846027309597, 10371.79256414875, 1829.652366050446, 1829.7163939589252, 1841.2174825699158, 1892.4417296897448, 1916.1473144450724, 84764.16627090235, 91973.74406966931, 92463.53768231183, 102898.9710102897, 103456.44815327702, 11187.776244475228, 11361.488733007034, 11445.88554114465, 11472.239020806272, 11631.59289424883, 14421.78367324491, 14428.206448645426, 14437.639059023608, 14446.205458699873, 14449.205293708947, 8031.357491400674, 8182.140875208018, 8212.0, 8307.878436411129, 8308.750737477885, 7641.961790191013, 7840.529568225925, 7841.372690184771, 7957.442978991415, 8136.674533018731, 4462.152191083684, 4521.841735539306, 4523.638108951276, 4577.359169716243, 4662.4405071390975, 1875.5498680316625, 1919.2898627507911, 1920.3470819921295, 1925.865189436726, 1930.3726288956145, 973.73709098543, 995.7510622344414, 1007.6876168387823, 1009.9899001009895, 1040.9323393979366, 20150.496237593885, 20466.692999605017, 20533.58932821335, 20601.484962875747, 20924.953752312627, 900.3112618846587, 945.7115579748242, 990.663174520652, 996.8984272379062, 1032.2722480651123, 10330.276880618434, 10714.0, 10788.975047370488, 10829.07952824003, 10832.783344333227, 540264.0, 540900.0, 545712.0, 564537.3546264485, 564606.0, 87829.5608521966, 119017.0, 128608.35695821648, 132448.0, 133051.0, 954.9665761698308, 963.9325247232626, 973.7468258252763, 981.7643765496372, 982.0670363155032, 17616.0, 18079.63840723152, 18095.638087238443, 18173.182206800462, 18188.72716909221, 47318.76340618275, 50083.24875126803, 50492.24261636005, 51851.999980003726, 52265.0, 57599.71200143972, 73754.89367659383, 75259.0, 75629.86555201566, 77507.0, 7009.334113259232, 7264.455165862476, 7729.845403091794, 7845.333146682596, 8289.544075075928, 11614.88385116138, 11750.882491174978, 11783.057355411718, 11957.282563046245, 12679.556215532411, 10391.896081039093, 10607.94696026515, 10835.404052777172, 11221.382823944761, 11328.660140195807, 10569.682909512727, 10768.892311076788, 10814.459277036274, 11143.164262680493, 11182.496787644413, 10830.869426242658, 11049.668509944715, 11198.88801112011, 11386.886131138583, 11430.599929002445, 7219.819504512429, 7249.963750181215, 7345.22875098107, 7368.189499155196, 7529.058867641545, 8554.78613034679, 10687.0, 11056.949589788957, 11126.721831954266, 11275.548978040786], "value": 9169.49724554194, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1185.4546908422126, 1398.5874167120699, 1415.837178724447, 1416.5608661314993, 1426.9714605707886, 7956.562389068599, 8381.91618083819, 8399.412041157122, 8399.664013439471, 8407.49555026698, 7344.0, 7610.467267291292, 7640.617969101537, 7878.33034192093, 8126.91873081269, 2260.9095636174534, 2305.0664480885184, 2306.884655767213, 2307.261676263596, 2308.4228942764307, 2279.0969540433757, 2316.328264803205, 2319.188284100568, 2320.570694421529, 2325.2094287942095, 2022.6258142243732, 2098.0663604695915, 2222.944426389337, 2274.454131008554, 2303.0442366417915, 2276.931692049233, 2289.4505318723554, 2291.5302363015544, 2292.5071109711494, 2298.2300929188827, 2290.1755368067484, 2305.4697419593585, 2307.976920230793, 2316.409315624505, 2319.6404557293645, 8246.876296855551, 8265.876011859827, 8369.03756068054, 8369.995600527976, 8373.0, 4433.689641725081, 4444.444444444444, 4987.750612469362, 5081.4918508148885, 5153.716545589991, 3313.8177400242976, 3464.41105012146, 3547.8580856765698, 3549.201429678302, 3553.1650062235353, 1096.6984079378155, 1202.188522747151, 1210.5339444313915, 1235.2341548240058, 1239.522783728262, 8648.740537783939, 10667.359958402521, 10712.03591676745, 10713.625023124232, 10714.03573678365, 4253.149370125925, 4352.934705979413, 4407.515173330931, 4408.25059739845, 4412.42638457005, 8655.56722163902, 8662.047174810767, 8664.913350866536, 8668.913310866938, 8682.652693892174, 5035.320231768665, 5036.420811606639, 5057.949420505822, 5059.974700126476, 5061.468545802712, 4578.153041687232, 4586.931196032062, 4724.787384567703, 4754.809807607657, 4786.760661966888, 3430.6569343065494, 3491.2493813830147, 3524.2599054198904, 3556.4309710446723, 3560.394732895414, 1080.486768784821, 1103.2828661370174, 1105.4196546812987, 1114.4093630375914, 1114.4706264524286, 753.4575105923835, 771.4484143837184, 886.2909672262165, 894.4454438248198, 939.4598106088889, 5442.755076021588, 5530.806421775299, 5761.164631128547, 5817.7963771267805, 6029.88447137281, 922.6678395777515, 937.7139972308375, 961.6874515782399, 974.4640447753849, 1006.1196453103535, 3248.8538015789345, 3651.5253017107607, 3676.584514961759, 3702.4631428442726, 3759.473673685686, 9649.228061755042, 9844.261680374, 9954.601815927283, 9966.80066398668, 10024.047715467019, 8277.882485864451, 9889.703308900744, 9900.108990190918, 9911.553980070914, 9953.402795832273, 7195.532290401212, 7377.3360397564475, 7677.769666910001, 7708.38332933364, 7765.805854853674, 5512.118061110203, 5597.6845441322075, 5615.6630602164005, 5697.373288938131, 5764.855878603068, 8819.338549608678, 8947.552622368985, 9025.548722563977, 9092.590833412645, 9161.908380916373, 5748.936446757363, 9184.403013804082, 9335.113164249386, 9406.0, 9427.481488518197, 7020.333068358498, 7161.57030578167, 7184.748533801292, 7198.136223652986, 7202.135743710788, 7624.5425274483705, 7693.961530192313, 7796.84406311882, 7801.804954876174, 7824.21757824213, 7022.859542809218, 7045.330693584101, 7092.04257425241, 7097.858042839011, 7228.674709638184, 6932.341427564374, 6998.335158159966, 7014.438844892498, 7034.577925324497, 7043.154821421463, 6149.96925015372, 6294.0, 6315.273743519554, 6371.585846919936, 6382.20222472191, 4095.689379398683, 4737.502562231054, 4746.715197088186, 4783.904321913473, 4909.656324057424, 4640.211164102043, 4719.0, 4790.233562629963, 4836.758162091811, 4910.754462276944], "value": 4303.523576933514, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.5454817788295, 349.63637816670655, 349.69751165242076, 360.9458581212816, 361.8425984696657, 4360.825566977325, 5156.484351564842, 5172.379334465875, 5224.660397074187, 5340.759665815038, 4990.825321113762, 5104.566111880495, 5161.819336323229, 5259.184826351912, 5261.8947621047555, 3035.4080954213937, 3342.46520556711, 3685.778853268806, 3789.7915614641183, 4027.7583344999393, 3771.6416940390754, 3856.093817952779, 4052.29084910141, 4053.756774593519, 4078.490188726409, 4073.4704488416614, 4075.5924407559305, 4082.81627326771, 4083.4079058536495, 4098.73358231714, 3894.182221733411, 4016.3332217129855, 4066.755994640316, 4118.279301122321, 4132.359484279941, 2898.6086478655193, 3095.845207739604, 3280.704121871871, 3622.6196249393697, 3634.890953271406, 3997.960020399817, 4280.443542339507, 4982.551570358649, 5051.419086805027, 5091.319864444725, 3998.5601583825755, 4493.0, 4647.558481944244, 4651.883702907455, 4664.906701865978, 5080.644354895159, 5285.947140528622, 5432.70120143392, 5441.619496305472, 5442.333256704186, 3965.6232657897453, 3969.868587452591, 4030.355143177107, 4031.294523458412, 4033.2135233629456, 4950.777215025332, 5448.591355648303, 5660.377358490604, 5734.569907256972, 5767.538596912237, 3598.7480876338404, 3836.0218144373007, 3903.512060992376, 4061.3501839705505, 4112.712110152261, 3385.4921761735936, 3832.827522761482, 3947.8945895149404, 3959.089409435788, 4133.359329303963, 3815.0624019504826, 3947.6644485218503, 4618.307253911873, 4631.698939568917, 4658.277966915133, 4721.544796705763, 5421.132618781055, 5434.538064264502, 5454.9271276915315, 5458.317710286215, 1129.6215767717906, 1220.3288191494642, 1236.164296020409, 1240.2744394529302, 1246.389269258057, 784.5096814490943, 837.4891316297054, 843.7173546861748, 851.9776268477727, 856.6273670953155, 4326.848560300375, 4674.813007479731, 4902.926456103161, 4905.435874874435, 4913.774154227362, 4560.407147070926, 4814.7833347499445, 4950.925736113961, 5058.83646761247, 5379.310344827586, 4047.494063242095, 4410.0, 4644.8374306899095, 4885.951140488621, 4892.461829198785, 2538.3727841684135, 2542.9872850635998, 2694.669111218782, 2699.9865000675263, 2856.8285902845896, 4541.186342476985, 5472.781088756406, 5534.391216966131, 5535.557155427556, 5540.944590554042, 4621.798332433592, 4895.534924182197, 5002.474740152305, 5369.946300537023, 5373.435789242151, 4164.312888373384, 4513.593776559994, 4871.780769865364, 5315.920261196085, 5349.652272602269, 4826.975865120792, 5180.503160366813, 5222.3733152022005, 5277.604179686462, 5310.654807437504, 4488.394066801071, 4700.730802683324, 5157.421289355322, 5213.687178769286, 5220.608454365862, 5433.483819037185, 5523.536262890257, 5633.295838020247, 5684.857878553069, 5909.438603332676, 3345.799252044885, 3735.7384983050924, 3746.756460830037, 3815.389537673959, 3829.5021647185686, 5657.717114144359, 5660.688662123623, 5708.743106560298, 5713.971430142823, 5730.3410107838135, 5294.70279781456, 5396.354111995879, 5426.07756681373, 5452.57360474585, 5470.316210473691, 4343.543927887653, 4658.534146585314, 5245.423003469539, 5415.431379705232, 5577.553795696415, 4336.934945975876, 4825.9758701206265, 5374.838754837361, 5404.83785486436, 5410.648307859977, 4623.352730617649, 5090.8291093048065, 5216.295800066942, 5430.8099216527235, 5547.805826796042, 4836.5973867578, 4977.552020318044, 5231.985685511182, 5266.733945504154, 5343.732813359395], "value": 3931.741921347283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.6822442033392, 728.806866180462, 734.1153909539006, 740.896274521567, 754.3211110001, 12064.2761434314, 15575.532734017983, 15578.143202123878, 15586.84413155868, 15596.298166582503, 13977.371018304175, 14212.218327991955, 14218.573442796733, 14227.146371217708, 14272.500462483815, 687.5153017122941, 718.317598281632, 873.4104479476359, 875.1423604867234, 875.6497401039603, 695.9826004349881, 705.9869087858913, 855.9914400856005, 872.4372779557182, 898.27239935652, 944.0795224655974, 948.8624149498319, 949.1552518258756, 950.2445555783135, 950.9286803489731, 896.6906417286026, 915.1809130827938, 935.1863878425745, 935.223764275653, 939.3377668743519, 529.2352550564436, 549.9945000550023, 661.2032500836528, 680.6426626021329, 683.7572661705073, 14904.552863414116, 14907.478238261718, 14943.476978305707, 14951.177685227309, 14973.401063957428, 13729.656758581015, 13776.793348099787, 13813.51652692151, 13832.723345533133, 13852.445902163901, 12663.30351830649, 12714.249296457567, 12826.551070712574, 12920.095593308472, 13272.867271327259, 4385.079133381962, 4410.184115938561, 4431.446069241345, 4512.210363186429, 4654.930176047362, 102797.0, 103524.0, 104311.4784426073, 104698.47650761774, 105467.47266263697, 9140.999999999867, 10489.318194317346, 10834.241603087708, 10905.236633435583, 11254.099672026217, 20909.163633454795, 21117.8385188813, 21162.365129046153, 21217.257395991375, 21218.57562848765, 7897.842043159105, 8006.15935326794, 8129.187081291823, 8170.836583268301, 8419.0, 4299.935500967488, 4350.543192964693, 4380.8685739427865, 4412.977935110304, 4508.346289788028, 3139.21519620095, 3589.353916295092, 3654.908627284339, 3696.6488183622514, 3703.925921481555, 649.9187601549806, 651.4202359899693, 663.2770280394296, 669.5982410553629, 671.993280067193, 611.4832966143601, 641.3330136657917, 642.2998931164959, 653.3564439027554, 681.5774219983542, 8775.385722999456, 9233.630654773733, 9234.028164531746, 9485.098915603006, 9519.23846092311, 627.5199472403615, 651.0299653516226, 666.9866602668017, 686.9553479023848, 691.4917535611322, 4270.765107919095, 4414.3157810539415, 4416.84541041062, 4430.977845110754, 4458.576435238647, 99783.0, 102330.0, 105154.0, 106535.33661658365, 109976.0, 70024.0, 76337.23662763301, 78662.0, 85835.57082214548, 87800.56099719461, 81210.59394703107, 82576.0, 82817.58591207005, 83379.5831020841, 84778.15221847827, 8645.654173832976, 8651.351148664002, 8656.350773691873, 8666.393352465268, 8772.29821614282, 52937.0, 54825.177622336494, 55173.44826551683, 55425.0, 56252.43747562636, 52495.47504525058, 57842.7107864458, 71848.28151718416, 72572.28280161772, 74229.62885185756, 11857.525698971944, 11900.047996160114, 11909.106816988933, 12061.75876482483, 12111.183656381452, 12297.50809967627, 12606.306653133799, 12678.429470673658, 12981.479095854786, 13012.479500819862, 11743.014749970102, 11929.138158744043, 12076.275423474619, 12080.39598020113, 12482.625521234377, 11745.0, 11804.291742495478, 11960.880391195977, 12096.697582560506, 12605.74788504243, 11591.478383472933, 11834.881651183377, 12088.576899808464, 12142.210756300812, 12379.814302785646, 8078.87881681787, 9477.383970041925, 10091.495425228562, 10162.18702503783, 10653.680389588324, 9627.454195799006, 9639.0, 9743.31796774219, 9746.171575415885, 9775.916895280621], "value": 7301.293931510711, "unit": "score"}], "name": "dromaeo_css", "value": 7076.91474632101, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 114.0, 112.0, 147.0, 112.0, 109.0, 110.0, 111.0, 132.0, 115.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 185.0, 184.0, 184.0, 183.0, 180.0, 186.0, 183.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 88.0, 60.0, 62.0, 60.0, 58.0, 75.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 88.0, 70.0, 69.0, 69.0, 84.0, 69.0, 92.0, 115.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 87.0, 89.0, 87.0, 92.0, 87.0, 90.0, 86.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 93.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 101.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 84.0, 85.0, 87.0, 87.0, 86.0, 87.0, 88.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 55.0, 55.0, 57.0, 57.0, 55.0, 56.0, 55.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 75.0, 77.0, 75.0, 71.0, 70.0, 72.0, 68.0, 75.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 86.0, 91.0, 84.0, 89.0, 86.0, 88.0, 88.0, 92.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 140.0, 139.0, 139.0, 141.0, 144.0, 134.0, 205.0, 179.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 174.0, 172.0, 172.0, 175.0, 171.0, 176.0, 172.0, 172.0, 174.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 68.0, 67.0, 68.0, 67.0, 64.0, 66.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1446.9999999999998, "alertThreshold": 2.0}]} 04:54:19 INFO - Return code: 0 04:54:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:54:19 INFO - # TBPL SUCCESS # 04:54:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:54:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:54:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:54:19 INFO - 2016-12-27 04:54:19.817 screenresolution[1696:16289] starting screenresolution argv=screenresolution get 04:54:19 INFO - 2016-12-27 04:54:19.820 screenresolution[1696:16289] Display 0: 1600x1200x32@60 04:54:19 INFO - 2016-12-27 04:54:19.825 screenresolution[1697:16292] starting screenresolution argv=screenresolution list 04:54:19 INFO - Available Modes on Display 0 04:54:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:54:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:54:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:54:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:54:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:54:20 INFO - Intel Iris: 04:54:20 INFO - Chipset Model: Intel Iris 04:54:20 INFO - Type: GPU 04:54:20 INFO - Bus: Built-In 04:54:20 INFO - VRAM (Dynamic, Max): 1536 MB 04:54:20 INFO - Vendor: Intel (0x8086) 04:54:20 INFO - Device ID: 0x0a2e 04:54:20 INFO - Revision ID: 0x0009 04:54:20 INFO - Displays: 04:54:20 INFO - Display: 04:54:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:54:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:54:20 INFO - Display Serial Number: 1600x1200 60 04:54:20 INFO - Main Display: Yes 04:54:20 INFO - Mirror: Off 04:54:20 INFO - Online: Yes 04:54:20 INFO - Rotation: Supported 04:54:20 INFO - Return code: 0 04:54:20 INFO - Running post-action listener: _resource_record_post_action 04:54:20 INFO - [mozharness: 2016-12-27 12:54:20.059409Z] Finished run-tests step (success) 04:54:20 INFO - Running post-run listener: _resource_record_post_run 04:54:20 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33233920; Write bytes: 550728704; Read time: 308; Write time: 1884 04:54:20 INFO - TinderboxPrint: CPU usage
53.8% 04:54:20 INFO - TinderboxPrint: I/O read bytes / time
33,233,920 / 308 04:54:20 INFO - TinderboxPrint: I/O write bytes / time
550,728,704 / 1,884 04:54:20 INFO - TinderboxPrint: CPU idle
2,224.4 (46.2%) 04:54:20 INFO - TinderboxPrint: CPU system
960.6 (20.0%) 04:54:20 INFO - TinderboxPrint: CPU user
1,624.7 (33.8%) 04:54:20 INFO - TinderboxPrint: Swap in / out
277,843,968 / 0 04:54:20 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243399168; Write bytes: 237331968; Read time: 17854; Write time: 490 04:54:20 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31157248; Write bytes: 297860608; Read time: 284; Write time: 1358 04:54:20 INFO - Running post-run listener: _upload_blobber_files 04:54:20 INFO - Blob upload gear active. 04:54:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:54:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:54:20 INFO - (blobuploader) - INFO - Open directory for files ... 04:54:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:54:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:54:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:54:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:54:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:54:21 INFO - (blobuploader) - INFO - Done attempting. 04:54:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:54:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:54:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:54:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:54:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:54:22 INFO - (blobuploader) - INFO - Done attempting. 04:54:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:54:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:54:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:54:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:54:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:54:23 INFO - (blobuploader) - INFO - Done attempting. 04:54:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:54:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:54:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:54:26 INFO - (blobuploader) - INFO - Done attempting. 04:54:26 INFO - (blobuploader) - INFO - Iteration through files over. 04:54:26 INFO - Return code: 0 04:54:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:54:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:54:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45b7f6d0a6bec1afec3fcf59450a04d135422f78e96242ca761a34e529b2e45ded74810838c267ae4ceef1f842f829fed79cf1b0b5f75ee9d23aa0a62177c563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7135dcb3b7d0221ed86a8b2fe289bbb392a6bc18c5ffbb631e10b982eafdebee1072ba2c90993b6056f207a18849fddf97d229968fac7f183d3994f123714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21381e6ae85d20b67fd1777b38030f6f0351ba5932b1eaddcaa9be38a88e2f8d50e4e2ab3cdd1493158135f423bfff9ba411c882e2f77d566ffa42eec58ef067", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be37e3ab0663d2a663017add5d8b74e340681b16a695b0769bbea0b968b1118dbbc464d48570b0fa83f0e314d48f7ea40765e600dbfb0343c2fa797500b5aa6"} 04:54:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:54:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:54:26 INFO - Contents: 04:54:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45b7f6d0a6bec1afec3fcf59450a04d135422f78e96242ca761a34e529b2e45ded74810838c267ae4ceef1f842f829fed79cf1b0b5f75ee9d23aa0a62177c563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7135dcb3b7d0221ed86a8b2fe289bbb392a6bc18c5ffbb631e10b982eafdebee1072ba2c90993b6056f207a18849fddf97d229968fac7f183d3994f123714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21381e6ae85d20b67fd1777b38030f6f0351ba5932b1eaddcaa9be38a88e2f8d50e4e2ab3cdd1493158135f423bfff9ba411c882e2f77d566ffa42eec58ef067", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be37e3ab0663d2a663017add5d8b74e340681b16a695b0769bbea0b968b1118dbbc464d48570b0fa83f0e314d48f7ea40765e600dbfb0343c2fa797500b5aa6"} 04:54:26 INFO - Running post-run listener: copy_logs_to_upload_dir 04:54:26 INFO - Copying logs to upload dir... 04:54:26 INFO - mkdir: /builds/slave/test/build/upload/logs 04:54:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.630769 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-27 04:54:26.176884) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 04:54:26.180079) ========= 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.WX0lseo5at/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.XxaSC7bib8/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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/45b7f6d0a6bec1afec3fcf59450a04d135422f78e96242ca761a34e529b2e45ded74810838c267ae4ceef1f842f829fed79cf1b0b5f75ee9d23aa0a62177c563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7135dcb3b7d0221ed86a8b2fe289bbb392a6bc18c5ffbb631e10b982eafdebee1072ba2c90993b6056f207a18849fddf97d229968fac7f183d3994f123714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21381e6ae85d20b67fd1777b38030f6f0351ba5932b1eaddcaa9be38a88e2f8d50e4e2ab3cdd1493158135f423bfff9ba411c882e2f77d566ffa42eec58ef067", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be37e3ab0663d2a663017add5d8b74e340681b16a695b0769bbea0b968b1118dbbc464d48570b0fa83f0e314d48f7ea40765e600dbfb0343c2fa797500b5aa6"} build_url:https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010654 build_url: 'https://queue.taskcluster.net/v1/task/IQKpICXDSsuOOpsSMQIYfw/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/45b7f6d0a6bec1afec3fcf59450a04d135422f78e96242ca761a34e529b2e45ded74810838c267ae4ceef1f842f829fed79cf1b0b5f75ee9d23aa0a62177c563", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7135dcb3b7d0221ed86a8b2fe289bbb392a6bc18c5ffbb631e10b982eafdebee1072ba2c90993b6056f207a18849fddf97d229968fac7f183d3994f123714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21381e6ae85d20b67fd1777b38030f6f0351ba5932b1eaddcaa9be38a88e2f8d50e4e2ab3cdd1493158135f423bfff9ba411c882e2f77d566ffa42eec58ef067", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be37e3ab0663d2a663017add5d8b74e340681b16a695b0769bbea0b968b1118dbbc464d48570b0fa83f0e314d48f7ea40765e600dbfb0343c2fa797500b5aa6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 04:54:26.214844) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:54:26.215168) ========= 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.WX0lseo5at/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.XxaSC7bib8/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.005289 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 04:54:26.263404) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 04:54:26.263870) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 04:54:26.264187) ========= ========= Total master_lag: 0.21 =========