Back to build
Raw log | Switch to full mode | Login

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmptPLOHt.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/30DKnBCr --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpY9e9XTmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpzPmFbw.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpkG6lTK.json in dir /b/s/w/ir/k: allow_subannotations: False cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmptPLOHt.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/30DKnBCr', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpY9e9XTmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpzPmFbw.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/s/w/ir/tmp/t/tmpkG6lTK.json'] env: FrozenDict([]) env_prefixes: {'mapping': FrozenDict([('PATH', ('/b/s/w/ir/cache/swarming_client',))]), 'pathsep': ':'} env_suffixes: {'mapping': FrozenDict([]), 'pathsep': ':'} infra_step: False name_tokens: ['ios_chrome_web_egtests (iPhone X iOS 11.4) on Mac-10.13.6'] ok_ret: ALL_OK step_test_data: gen_default_step_test_data(...) trigger_specs: [] full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.Cs0xEy1Dbs/Render BOTO_CONFIG: /b/s/w/ir/tmp/gsutil-task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 60990 DOCKER_CONFIG: /b/s/w/ir/tmp/docker-cfg-task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker-tmp-task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /Users/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git-home-task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8915151825189839184 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/ir/tmp/luci_context.435668887 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PWD: /b/s/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SSH_AUTH_SOCK: /private/tmp/com.apple.launchd.bs7QTpklaT/Listeners SWARMING_BOT_ID: vm124-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 44704a66ecf34611 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot VERSIONER_PYTHON_PREFER_32_BIT: no VERSIONER_PYTHON_VERSION: 2.7 VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython XPC_FLAGS: 0x0 XPC_SERVICE_NAME: 0 _: /b/s/w/ir/cipd_bin_packages/vpython __CF_USER_TEXT_ENCODING: 0x1F4:0x0:0x0 Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-04-25 20:00:14,069 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/30DKnBCr' 2019-04-25 20:00:14,069 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/30DKnBCr' already exists! 2019-04-25 20:00:14,069 - root: [WARNING] task_output_dir existing content: [] 2019-04-25 20:00:14,069 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpkG6lTK.json -output-dir /b/s/w/ir/k/recipe_cleanup/30DKnBCr -task-summary-json /b/s/w/ir/tmp/t/tmpzPmFbw.json [D2019-04-25T20:00:14.090899-07:00 21038 0 auth.go:1265] Minting a new token {"key":"luci_ctx/5406046c9b24d01d68ce2ccfcf94c0cb5ce8251d7e0650820b820ad3587c15ee", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-25T20:00:14.091012-07:00 21038 0 luci_ctx.go:138] POST http://127.0.0.1:60977/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/5406046c9b24d01d68ce2ccfcf94c0cb5ce8251d7e0650820b820ad3587c15ee", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-25T20:00:14.092902-07:00 21038 0 auth.go:1222] Token expires in 39m46.907115s {"key":"luci_ctx/5406046c9b24d01d68ce2ccfcf94c0cb5ce8251d7e0650820b820ad3587c15ee", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 4470562e1fcf1b10: exit 0 [I 2019-04-25 19:41:34] About to install Xcode 10b61 in Xcode.app for ios [07:41:36:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [07:41:44:DEBUG] Setting sigterm handler. [07:41:46:INFO] User defaults from command line: [07:41:46:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/68B3C0BE-9F0B-46A5-A5C7-0D87A5667712 [07:41:46:INFO] [07:41:46:INFO] Testing started on 'iPhone X' [07:41:46:INFO] 2019-04-25 19:41:46.933 xcodebuild[14789:238794] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [07:41:46:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-ebcbniioggodgictkcjotqyfdsbv/Logs/Test/Test-Transient Testing-2019.04.25_19-41-46--0700.xcresult/1_Test/Diagnostics/ios_chrome_web_egtests_module-1128659E-C65D-45A1-9E13-EE5583B52E2F/ios_chrome_web_egtests_module-4586DE87-371C-44CD-A8D8-21F8DF5576A6/Session-ios_chrome_web_egtests_module-2019-04-25_194146-jVOxMk.log [07:41:46:INFO] 2019-04-25 19:41:46.934 xcodebuild[14789:238783] [MT] IDETestOperationsObserverDebug: (0B7F7B48-D53E-4BC1-B4F5-696C1D206958) Beginning test session ios_chrome_web_egtests_module-0B7F7B48-D53E-4BC1-B4F5-696C1D206958 at 2019-04-25 19:41:46.934 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fba54995980> { [07:41:46:INFO] SimDevice: iPhone X (AF2949C4-067C-4A64-BF31-280B975A900B, iOS 11.4, Shutdown) [07:41:46:INFO] } (11.4 (15F79)) [07:42:11:INFO] 2019-04-25 19:42:11.397 ios_chrome_web_egtests[14908:240374] Crash handler setup started. [07:42:11:INFO] 2019-04-25 19:42:11.398 ios_chrome_web_egtests[14908:240374] Crash handler setup completed. [07:42:11:INFO] 2019-04-25 19:42:11.398 ios_chrome_web_egtests[14908:240374] Enabling accessibility for automation on Simulator. [07:42:11:INFO] objc[14908]: Class VCWeakObjectHolder is implemented in both /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/Frameworks/ViceroyTrace.framework/ViceroyTrace (0x149ed24d0) and /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/AVConference (0x149ce8e38). One of the two will be used. Which one is undefined. [07:42:13:INFO] 2019-04-25 19:42:13.860 ios_chrome_web_egtests[14908:240374] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [07:42:13:INFO] 2019-04-25 19:42:13.861 ios_chrome_web_egtests[14908:240374] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [07:42:13:INFO] 2019-04-25 19:42:13.861 ios_chrome_web_egtests[14908:240374] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [07:42:13:INFO] 2019-04-25 19:42:13.861 ios_chrome_web_egtests[14908:240374] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [07:42:15:INFO] 2019-04-25 19:42:15.152 ios_chrome_web_egtests[14908:240374] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f92a802a800>. [07:42:15:INFO] [0425/194215.601528:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/AF2949C4-067C-4A64-BF31-280B975A900B/data/Containers/Data/Application/DDCD1C45-9E0E-4BCF-9DEC-B12CE2EA7AAE/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/AF2949C4-067C-4A64-BF31-280B975A900B/data/Containers/Data/Application/DDCD1C45-9E0E-4BCF-9DEC-B12CE2EA7AAE/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [07:42:15:INFO] [0425/194215.810971:ERROR:send_tab_to_self_client_service_ios.mm(120)] Not implemented reached in virtual void send_tab_to_self::SendTabToSelfClientServiceIOS::EntriesRemovedRemotely(const std::vector<std::string> &) [07:42:22:INFO] Test Suite 'All tests' started at 2019-04-25 19:42:22.321 [07:42:22:INFO] Test Suite 'ios_chrome_web_egtests.app' started at 2019-04-25 19:42:22.322 [07:42:22:INFO] Test Suite 'BrowsingPreventDefaultTestCase' started at 2019-04-25 19:42:22.323 [07:42:22:INFO] [0425/194222.331035:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:42:24:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesStopPropagation]' started. [07:42:26:INFO] [0425/194226.615997:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:42:29:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesStopPropagation]' passed (5.359 seconds). [07:42:29:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesTargetBlank]' started. [07:42:34:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesTargetBlank]' passed (5.223 seconds). [07:42:34:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesWindowOpen]' started. [07:42:41:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesWindowOpen]' passed (6.479 seconds). [07:42:41:INFO] Test Suite 'BrowsingPreventDefaultTestCase' passed at 2019-04-25 19:42:41.252. [07:42:41:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 17.061 (18.929) seconds [07:42:41:INFO] Test Suite 'BrowsingTestCase' started at 2019-04-25 19:42:41.253 [07:42:41:INFO] [0425/194241.254240:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:42:41:INFO] Test Case '-[BrowsingTestCase testBrowsingJavaScriptBasedNavigation]' started. [07:42:43:INFO] [0425/194243.669014:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:42:49:INFO] Test Case '-[BrowsingTestCase testBrowsingJavaScriptBasedNavigation]' passed (8.230 seconds). [07:42:49:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagation]' started. [07:42:55:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagation]' passed (6.440 seconds). [07:42:55:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagationRelativePath]' started. [07:43:02:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagationRelativePath]' passed (6.369 seconds). [07:43:02:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromScript]' started. [07:43:04:INFO] 2019-04-25 19:43:04.451 ios_chrome_web_egtests[14908:240374] -- Earl Grey Test Skipped -- Tab Title not displayed on handset. [07:43:06:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromScript]' passed (4.037 seconds). [07:43:06:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromURL]' started. [07:43:08:INFO] 2019-04-25 19:43:08.450 ios_chrome_web_egtests[14908:240374] -- Earl Grey Test Skipped -- Tab Title not displayed on handset. [07:43:10:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromURL]' passed (3.999 seconds). [07:43:10:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptNavigation]' started. [07:43:16:INFO] 2019-04-25 19:43:16.219 ios_chrome_web_egtests[14908:240374] Attempting to type key j. [07:43:16:INFO] 2019-04-25 19:43:16.224 ios_chrome_web_egtests[14908:240374] Tapping on key: j. [07:43:16:INFO] 2019-04-25 19:43:16.345 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:16:INFO] 2019-04-25 19:43:16.346 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:16:INFO] 2019-04-25 19:43:16.423 ios_chrome_web_egtests[14908:240374] Attempting to type key v. [07:43:16:INFO] 2019-04-25 19:43:16.426 ios_chrome_web_egtests[14908:240374] Tapping on key: v. [07:43:16:INFO] 2019-04-25 19:43:16.505 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:16:INFO] 2019-04-25 19:43:16.505 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:16:INFO] 2019-04-25 19:43:16.591 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:43:16:INFO] 2019-04-25 19:43:16.592 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:43:16:INFO] 2019-04-25 19:43:16.757 ios_chrome_web_egtests[14908:240374] Attempting to type key c. [07:43:16:INFO] 2019-04-25 19:43:16.759 ios_chrome_web_egtests[14908:240374] Tapping on key: c. [07:43:16:INFO] 2019-04-25 19:43:16.986 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:43:16:INFO] 2019-04-25 19:43:16.989 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:43:17:INFO] 2019-04-25 19:43:17.064 ios_chrome_web_egtests[14908:240374] Attempting to type key i. [07:43:17:INFO] 2019-04-25 19:43:17.064 ios_chrome_web_egtests[14908:240374] Tapping on key: i. [07:43:17:INFO] 2019-04-25 19:43:17.189 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:43:17:INFO] 2019-04-25 19:43:17.190 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:43:17:INFO] 2019-04-25 19:43:17.261 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:17:INFO] 2019-04-25 19:43:17.262 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:17:INFO] 2019-04-25 19:43:17.417 ios_chrome_web_egtests[14908:240374] Attempting to type key :. [07:43:17:INFO] 2019-04-25 19:43:17.519 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:17:INFO] 2019-04-25 19:43:17.608 ios_chrome_web_egtests[14908:240374] Tapping on key: :. [07:43:17:INFO] 2019-04-25 19:43:17.677 ios_chrome_web_egtests[14908:240374] Attempting to type key w. [07:43:17:INFO] 2019-04-25 19:43:17.985 ios_chrome_web_egtests[14908:240374] Tapping on key: more, letters. [07:43:18:INFO] 2019-04-25 19:43:18.038 ios_chrome_web_egtests[14908:240374] Tapping on key: w. [07:43:18:INFO] 2019-04-25 19:43:18.095 ios_chrome_web_egtests[14908:240374] Attempting to type key i. [07:43:18:INFO] 2019-04-25 19:43:18.096 ios_chrome_web_egtests[14908:240374] Tapping on key: i. [07:43:18:INFO] 2019-04-25 19:43:18.154 ios_chrome_web_egtests[14908:240374] Attempting to type key n. [07:43:18:INFO] 2019-04-25 19:43:18.156 ios_chrome_web_egtests[14908:240374] Tapping on key: n. [07:43:18:INFO] 2019-04-25 19:43:18.215 ios_chrome_web_egtests[14908:240374] Attempting to type key d. [07:43:18:INFO] 2019-04-25 19:43:18.216 ios_chrome_web_egtests[14908:240374] Tapping on key: d. [07:43:18:INFO] 2019-04-25 19:43:18.275 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:43:18:INFO] 2019-04-25 19:43:18.276 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:43:18:INFO] 2019-04-25 19:43:18.431 ios_chrome_web_egtests[14908:240374] Attempting to type key w. [07:43:18:INFO] 2019-04-25 19:43:18.432 ios_chrome_web_egtests[14908:240374] Tapping on key: w. [07:43:18:INFO] 2019-04-25 19:43:18.488 ios_chrome_web_egtests[14908:240374] Attempting to type key .. [07:43:18:INFO] 2019-04-25 19:43:18.490 ios_chrome_web_egtests[14908:240374] Tapping on key: .. [07:43:18:INFO] 2019-04-25 19:43:18.630 ios_chrome_web_egtests[14908:240374] Attempting to type key l. [07:43:18:INFO] 2019-04-25 19:43:18.631 ios_chrome_web_egtests[14908:240374] Tapping on key: l. [07:43:18:INFO] 2019-04-25 19:43:18.690 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:43:18:INFO] 2019-04-25 19:43:18.690 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:43:18:INFO] 2019-04-25 19:43:18.849 ios_chrome_web_egtests[14908:240374] Attempting to type key c. [07:43:18:INFO] 2019-04-25 19:43:18.850 ios_chrome_web_egtests[14908:240374] Tapping on key: c. [07:43:18:INFO] 2019-04-25 19:43:18.908 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:18:INFO] 2019-04-25 19:43:18.909 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:19:INFO] 2019-04-25 19:43:19.060 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:19:INFO] 2019-04-25 19:43:19.061 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:19:INFO] 2019-04-25 19:43:19.118 ios_chrome_web_egtests[14908:240374] Attempting to type key i. [07:43:19:INFO] 2019-04-25 19:43:19.118 ios_chrome_web_egtests[14908:240374] Tapping on key: i. [07:43:19:INFO] 2019-04-25 19:43:19.286 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:43:19:INFO] 2019-04-25 19:43:19.287 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:43:19:INFO] 2019-04-25 19:43:19.345 ios_chrome_web_egtests[14908:240374] Attempting to type key n. [07:43:19:INFO] 2019-04-25 19:43:19.346 ios_chrome_web_egtests[14908:240374] Tapping on key: n. [07:43:19:INFO] 2019-04-25 19:43:19.483 ios_chrome_web_egtests[14908:240374] Attempting to type key =. [07:43:19:INFO] 2019-04-25 19:43:19.586 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:19:INFO] 2019-04-25 19:43:19.743 ios_chrome_web_egtests[14908:240374] Tapping on key: more, symbols. [07:43:19:INFO] 2019-04-25 19:43:19.813 ios_chrome_web_egtests[14908:240374] Tapping on key: =. [07:43:19:INFO] 2019-04-25 19:43:19.873 ios_chrome_web_egtests[14908:240374] Attempting to type key '. [07:43:19:INFO] 2019-04-25 19:43:19.875 ios_chrome_web_egtests[14908:240374] Tapping on key: '. [07:43:19:INFO] 2019-04-25 19:43:19.957 ios_chrome_web_egtests[14908:240374] Attempting to type key h. [07:43:19:INFO] 2019-04-25 19:43:19.958 ios_chrome_web_egtests[14908:240374] Tapping on key: h. [07:43:20:INFO] 2019-04-25 19:43:20.018 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:20:INFO] 2019-04-25 19:43:20.019 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:20:INFO] 2019-04-25 19:43:20.076 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:20:INFO] 2019-04-25 19:43:20.077 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:20:INFO] 2019-04-25 19:43:20.135 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:43:20:INFO] 2019-04-25 19:43:20.136 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:43:20:INFO] 2019-04-25 19:43:20.195 ios_chrome_web_egtests[14908:240374] Attempting to type key :. [07:43:20:INFO] 2019-04-25 19:43:20.298 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:20:INFO] 2019-04-25 19:43:20.353 ios_chrome_web_egtests[14908:240374] Tapping on key: :. [07:43:20:INFO] 2019-04-25 19:43:20.418 ios_chrome_web_egtests[14908:240374] Attempting to type key /. [07:43:20:INFO] 2019-04-25 19:43:20.419 ios_chrome_web_egtests[14908:240374] Tapping on key: /. [07:43:20:INFO] 2019-04-25 19:43:20.480 ios_chrome_web_egtests[14908:240374] Attempting to type key /. [07:43:20:INFO] 2019-04-25 19:43:20.481 ios_chrome_web_egtests[14908:240374] Tapping on key: /. [07:43:20:INFO] 2019-04-25 19:43:20.543 ios_chrome_web_egtests[14908:240374] Attempting to type key 1. [07:43:20:INFO] 2019-04-25 19:43:20.544 ios_chrome_web_egtests[14908:240374] Tapping on key: 1. [07:43:20:INFO] 2019-04-25 19:43:20.624 ios_chrome_web_egtests[14908:240374] Attempting to type key 2. [07:43:20:INFO] 2019-04-25 19:43:20.624 ios_chrome_web_egtests[14908:240374] Tapping on key: 2. [07:43:20:INFO] 2019-04-25 19:43:20.681 ios_chrome_web_egtests[14908:240374] Attempting to type key 7. [07:43:20:INFO] 2019-04-25 19:43:20.682 ios_chrome_web_egtests[14908:240374] Tapping on key: 7. [07:43:20:INFO] 2019-04-25 19:43:20.868 ios_chrome_web_egtests[14908:240374] Attempting to type key .. [07:43:20:INFO] 2019-04-25 19:43:20.870 ios_chrome_web_egtests[14908:240374] Tapping on key: .. [07:43:20:INFO] 2019-04-25 19:43:20.932 ios_chrome_web_egtests[14908:240374] Attempting to type key 0. [07:43:20:INFO] 2019-04-25 19:43:20.933 ios_chrome_web_egtests[14908:240374] Tapping on key: 0. [07:43:21:INFO] 2019-04-25 19:43:21.081 ios_chrome_web_egtests[14908:240374] Attempting to type key .. [07:43:21:INFO] 2019-04-25 19:43:21.083 ios_chrome_web_egtests[14908:240374] Tapping on key: .. [07:43:21:INFO] 2019-04-25 19:43:21.144 ios_chrome_web_egtests[14908:240374] Attempting to type key 0. [07:43:21:INFO] 2019-04-25 19:43:21.145 ios_chrome_web_egtests[14908:240374] Tapping on key: 0. [07:43:21:INFO] 2019-04-25 19:43:21.263 ios_chrome_web_egtests[14908:240374] Attempting to type key .. [07:43:21:INFO] 2019-04-25 19:43:21.264 ios_chrome_web_egtests[14908:240374] Tapping on key: .. [07:43:21:INFO] 2019-04-25 19:43:21.324 ios_chrome_web_egtests[14908:240374] Attempting to type key 1. [07:43:21:INFO] 2019-04-25 19:43:21.325 ios_chrome_web_egtests[14908:240374] Tapping on key: 1. [07:43:21:INFO] 2019-04-25 19:43:21.445 ios_chrome_web_egtests[14908:240374] Attempting to type key :. [07:43:21:INFO] 2019-04-25 19:43:21.446 ios_chrome_web_egtests[14908:240374] Tapping on key: :. [07:43:21:INFO] 2019-04-25 19:43:21.505 ios_chrome_web_egtests[14908:240374] Attempting to type key 5. [07:43:21:INFO] 2019-04-25 19:43:21.505 ios_chrome_web_egtests[14908:240374] Tapping on key: 5. [07:43:21:INFO] 2019-04-25 19:43:21.617 ios_chrome_web_egtests[14908:240374] Attempting to type key 0. [07:43:21:INFO] 2019-04-25 19:43:21.618 ios_chrome_web_egtests[14908:240374] Tapping on key: 0. [07:43:21:INFO] 2019-04-25 19:43:21.678 ios_chrome_web_egtests[14908:240374] Attempting to type key 5. [07:43:21:INFO] 2019-04-25 19:43:21.678 ios_chrome_web_egtests[14908:240374] Tapping on key: 5. [07:43:21:INFO] 2019-04-25 19:43:21.887 ios_chrome_web_egtests[14908:240374] Attempting to type key 6. [07:43:21:INFO] 2019-04-25 19:43:21.890 ios_chrome_web_egtests[14908:240374] Tapping on key: 6. [07:43:21:INFO] 2019-04-25 19:43:21.950 ios_chrome_web_egtests[14908:240374] Attempting to type key 0. [07:43:21:INFO] 2019-04-25 19:43:21.951 ios_chrome_web_egtests[14908:240374] Tapping on key: 0. [07:43:22:INFO] 2019-04-25 19:43:22.110 ios_chrome_web_egtests[14908:240374] Attempting to type key /. [07:43:22:INFO] 2019-04-25 19:43:22.111 ios_chrome_web_egtests[14908:240374] Tapping on key: /. [07:43:22:INFO] 2019-04-25 19:43:22.173 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:22:INFO] 2019-04-25 19:43:22.481 ios_chrome_web_egtests[14908:240374] Tapping on key: more, letters. [07:43:22:INFO] 2019-04-25 19:43:22.534 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:22:INFO] 2019-04-25 19:43:22.598 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:22:INFO] 2019-04-25 19:43:22.599 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:22:INFO] 2019-04-25 19:43:22.660 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:43:22:INFO] 2019-04-25 19:43:22.661 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:43:22:INFO] 2019-04-25 19:43:22.723 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:43:22:INFO] 2019-04-25 19:43:22.724 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:43:22:INFO] 2019-04-25 19:43:22.787 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:43:22:INFO] 2019-04-25 19:43:22.788 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:43:22:INFO] 2019-04-25 19:43:22.858 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:22:INFO] 2019-04-25 19:43:22.859 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:22:INFO] 2019-04-25 19:43:22.977 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:43:22:INFO] 2019-04-25 19:43:22.977 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:43:23:INFO] 2019-04-25 19:43:23.039 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:23:INFO] 2019-04-25 19:43:23.040 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:23:INFO] 2019-04-25 19:43:23.179 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:43:23:INFO] 2019-04-25 19:43:23.180 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:43:23:INFO] 2019-04-25 19:43:23.243 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:43:23:INFO] 2019-04-25 19:43:23.243 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:43:23:INFO] 2019-04-25 19:43:23.357 ios_chrome_web_egtests[14908:240374] Attempting to type key /. [07:43:23:INFO] 2019-04-25 19:43:23.461 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:23:INFO] 2019-04-25 19:43:23.515 ios_chrome_web_egtests[14908:240374] Tapping on key: /. [07:43:23:INFO] 2019-04-25 19:43:23.581 ios_chrome_web_egtests[14908:240374] Attempting to type key '. [07:43:23:INFO] 2019-04-25 19:43:23.583 ios_chrome_web_egtests[14908:240374] Tapping on key: '. [07:43:28:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptNavigation]' passed (18.466 seconds). [07:43:28:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptWithoutNavigation]' started. [07:43:34:INFO] 2019-04-25 19:43:34.574 ios_chrome_web_egtests[14908:240374] Attempting to type key j. [07:43:34:INFO] 2019-04-25 19:43:34.577 ios_chrome_web_egtests[14908:240374] Tapping on key: j. [07:43:34:INFO] 2019-04-25 19:43:34.665 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:34:INFO] 2019-04-25 19:43:34.666 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:34:INFO] 2019-04-25 19:43:34.738 ios_chrome_web_egtests[14908:240374] Attempting to type key v. [07:43:34:INFO] 2019-04-25 19:43:34.739 ios_chrome_web_egtests[14908:240374] Tapping on key: v. [07:43:34:INFO] 2019-04-25 19:43:34.815 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:43:34:INFO] 2019-04-25 19:43:34.816 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:43:34:INFO] 2019-04-25 19:43:34.891 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:43:34:INFO] 2019-04-25 19:43:34.892 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:43:34:INFO] 2019-04-25 19:43:34.962 ios_chrome_web_egtests[14908:240374] Attempting to type key c. [07:43:34:INFO] 2019-04-25 19:43:34.963 ios_chrome_web_egtests[14908:240374] Tapping on key: c. [07:43:35:INFO] 2019-04-25 19:43:35.205 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:43:35:INFO] 2019-04-25 19:43:35.206 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:43:35:INFO] 2019-04-25 19:43:35.279 ios_chrome_web_egtests[14908:240374] Attempting to type key i. [07:43:35:INFO] 2019-04-25 19:43:35.280 ios_chrome_web_egtests[14908:240374] Tapping on key: i. [07:43:35:INFO] 2019-04-25 19:43:35.354 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:43:35:INFO] 2019-04-25 19:43:35.355 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:43:35:INFO] 2019-04-25 19:43:35.427 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:35:INFO] 2019-04-25 19:43:35.428 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:35:INFO] 2019-04-25 19:43:35.514 ios_chrome_web_egtests[14908:240374] Attempting to type key :. [07:43:35:INFO] 2019-04-25 19:43:35.618 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:35:INFO] 2019-04-25 19:43:35.670 ios_chrome_web_egtests[14908:240374] Tapping on key: :. [07:43:35:INFO] 2019-04-25 19:43:35.736 ios_chrome_web_egtests[14908:240374] Attempting to type key d. [07:43:36:INFO] 2019-04-25 19:43:36.043 ios_chrome_web_egtests[14908:240374] Tapping on key: more, letters. [07:43:36:INFO] 2019-04-25 19:43:36.096 ios_chrome_web_egtests[14908:240374] Tapping on key: d. [07:43:36:INFO] 2019-04-25 19:43:36.154 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:43:36:INFO] 2019-04-25 19:43:36.155 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:43:36:INFO] 2019-04-25 19:43:36.213 ios_chrome_web_egtests[14908:240374] Attempting to type key c. [07:43:36:INFO] 2019-04-25 19:43:36.215 ios_chrome_web_egtests[14908:240374] Tapping on key: c. [07:43:36:INFO] 2019-04-25 19:43:36.275 ios_chrome_web_egtests[14908:240374] Attempting to type key u. [07:43:36:INFO] 2019-04-25 19:43:36.275 ios_chrome_web_egtests[14908:240374] Tapping on key: u. [07:43:36:INFO] 2019-04-25 19:43:36.334 ios_chrome_web_egtests[14908:240374] Attempting to type key m. [07:43:36:INFO] 2019-04-25 19:43:36.335 ios_chrome_web_egtests[14908:240374] Tapping on key: m. [07:43:36:INFO] 2019-04-25 19:43:36.393 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:43:36:INFO] 2019-04-25 19:43:36.394 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:43:36:INFO] 2019-04-25 19:43:36.453 ios_chrome_web_egtests[14908:240374] Attempting to type key n. [07:43:36:INFO] 2019-04-25 19:43:36.454 ios_chrome_web_egtests[14908:240374] Tapping on key: n. [07:43:36:INFO] 2019-04-25 19:43:36.512 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:36:INFO] 2019-04-25 19:43:36.512 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:36:INFO] 2019-04-25 19:43:36.587 ios_chrome_web_egtests[14908:240374] Attempting to type key .. [07:43:36:INFO] 2019-04-25 19:43:36.588 ios_chrome_web_egtests[14908:240374] Tapping on key: .. [07:43:36:INFO] 2019-04-25 19:43:36.657 ios_chrome_web_egtests[14908:240374] Attempting to type key w. [07:43:36:INFO] 2019-04-25 19:43:36.657 ios_chrome_web_egtests[14908:240374] Tapping on key: w. [07:43:36:INFO] 2019-04-25 19:43:36.814 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:43:36:INFO] 2019-04-25 19:43:36.815 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:43:36:INFO] 2019-04-25 19:43:36.874 ios_chrome_web_egtests[14908:240374] Attempting to type key i. [07:43:36:INFO] 2019-04-25 19:43:36.875 ios_chrome_web_egtests[14908:240374] Tapping on key: i. [07:43:37:INFO] 2019-04-25 19:43:37.026 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:43:37:INFO] 2019-04-25 19:43:37.027 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:43:37:INFO] 2019-04-25 19:43:37.085 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:43:37:INFO] 2019-04-25 19:43:37.086 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:43:37:INFO] 2019-04-25 19:43:37.248 ios_chrome_web_egtests[14908:240374] Attempting to type key (. [07:43:37:INFO] 2019-04-25 19:43:37.352 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:37:INFO] 2019-04-25 19:43:37.406 ios_chrome_web_egtests[14908:240374] Tapping on key: (. [07:43:37:INFO] 2019-04-25 19:43:37.477 ios_chrome_web_egtests[14908:240374] Attempting to type key '. [07:43:37:INFO] 2019-04-25 19:43:37.480 ios_chrome_web_egtests[14908:240374] Tapping on key: '. [07:43:37:INFO] 2019-04-25 19:43:37.564 ios_chrome_web_egtests[14908:240374] Attempting to type key f. [07:43:37:INFO] 2019-04-25 19:43:37.565 ios_chrome_web_egtests[14908:240374] Tapping on key: f. [07:43:37:INFO] 2019-04-25 19:43:37.639 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:43:37:INFO] 2019-04-25 19:43:37.640 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:43:37:INFO] 2019-04-25 19:43:37.701 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:43:37:INFO] 2019-04-25 19:43:37.702 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:43:37:INFO] 2019-04-25 19:43:37.879 ios_chrome_web_egtests[14908:240374] Attempting to type key '. [07:43:37:INFO] 2019-04-25 19:43:37.982 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:38:INFO] 2019-04-25 19:43:38.038 ios_chrome_web_egtests[14908:240374] Tapping on key: '. [07:43:38:INFO] 2019-04-25 19:43:38.117 ios_chrome_web_egtests[14908:240374] Attempting to type key ). [07:43:38:INFO] 2019-04-25 19:43:38.219 ios_chrome_web_egtests[14908:240374] Tapping on key: more, numbers. [07:43:38:INFO] 2019-04-25 19:43:38.273 ios_chrome_web_egtests[14908:240374] Tapping on key: ). [07:43:38:INFO] 2019-04-25 19:43:38.341 ios_chrome_web_egtests[14908:240374] Attempting to type key [07:43:38:INFO] . [07:43:38:INFO] 2019-04-25 19:43:38.345 ios_chrome_web_egtests[14908:240374] Tapping on key: Go. [07:43:43:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptWithoutNavigation]' passed (14.373 seconds). [07:43:43:INFO] Test Case '-[BrowsingTestCase testBrowsingWindowDataLinkScriptRedirect]' started. [07:43:49:INFO] Test Case '-[BrowsingTestCase testBrowsingWindowDataLinkScriptRedirect]' passed (6.489 seconds). [07:43:49:INFO] Test Case '-[BrowsingTestCase testPDFLoadTitle]' started. [07:43:51:INFO] 2019-04-25 19:43:51.862 ios_chrome_web_egtests[14908:240374] -- Earl Grey Test Skipped -- Tab Title not displayed on handset. [07:43:53:INFO] Test Case '-[BrowsingTestCase testPDFLoadTitle]' passed (4.102 seconds). [07:43:53:INFO] Test Case '-[BrowsingTestCase testReload]' started. [07:44:04:INFO] Test Case '-[BrowsingTestCase testReload]' passed (10.622 seconds). [07:44:04:INFO] Test Case '-[BrowsingTestCase testTapLinkWithWebUIURL]' started. [07:44:10:INFO] Test Case '-[BrowsingTestCase testTapLinkWithWebUIURL]' passed (5.684 seconds). [07:44:10:INFO] Test Suite 'BrowsingTestCase' passed at 2019-04-25 19:44:10.077. [07:44:10:INFO] Executed 11 tests, with 0 failures (0 unexpected) in 88.811 (88.824) seconds [07:44:10:INFO] Test Suite 'CacheTestCase' started at 2019-04-25 19:44:10.078 [07:44:10:INFO] [0425/194410.080485:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:44:10:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnNavigateBackAndPageReload]' started. [07:44:12:INFO] 2019-04-25 19:44:12.298 ios_chrome_web_egtests[14908:240374] -- Earl Grey Test Disabled -- Disabled on iOS 10.3.1 and afterwards. [07:44:14:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnNavigateBackAndPageReload]' passed (4.147 seconds). [07:44:14:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnOpenNewTab]' started. [07:44:16:INFO] [0425/194416.762766:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:44:22:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnOpenNewTab]' passed (7.777 seconds). [07:44:22:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnSelectOmniboxSuggestion]' started. [07:44:27:INFO] 2019-04-25 19:44:27.430 ios_chrome_web_egtests[14908:240374] Attempting to type key c. [07:44:27:INFO] 2019-04-25 19:44:27.434 ios_chrome_web_egtests[14908:240374] Tapping on key: c. [07:44:27:INFO] 2019-04-25 19:44:27.528 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:44:27:INFO] 2019-04-25 19:44:27.529 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:44:27:INFO] 2019-04-25 19:44:27.606 ios_chrome_web_egtests[14908:240374] Attempting to type key c. [07:44:27:INFO] 2019-04-25 19:44:27.607 ios_chrome_web_egtests[14908:240374] Tapping on key: c. [07:44:27:INFO] 2019-04-25 19:44:27.683 ios_chrome_web_egtests[14908:240374] Attempting to type key h. [07:44:27:INFO] 2019-04-25 19:44:27.684 ios_chrome_web_egtests[14908:240374] Tapping on key: h. [07:44:27:INFO] 2019-04-25 19:44:27.845 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:44:27:INFO] 2019-04-25 19:44:27.846 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:44:27:INFO] 2019-04-25 19:44:27.960 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:44:27:INFO] 2019-04-25 19:44:27.961 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:44:28:INFO] 2019-04-25 19:44:28.301 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:44:28:INFO] 2019-04-25 19:44:28.301 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:44:28:INFO] 2019-04-25 19:44:28.411 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:44:28:INFO] 2019-04-25 19:44:28.412 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:44:28:INFO] 2019-04-25 19:44:28.596 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:44:28:INFO] 2019-04-25 19:44:28.598 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:44:28:INFO] 2019-04-25 19:44:28.676 ios_chrome_web_egtests[14908:240374] Attempting to type key f. [07:44:28:INFO] 2019-04-25 19:44:28.676 ios_chrome_web_egtests[14908:240374] Tapping on key: f. [07:44:28:INFO] 2019-04-25 19:44:28.859 ios_chrome_web_egtests[14908:240374] Attempting to type key i. [07:44:28:INFO] 2019-04-25 19:44:28.875 ios_chrome_web_egtests[14908:240374] Tapping on key: i. [07:44:29:INFO] 2019-04-25 19:44:29.060 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:44:29:INFO] 2019-04-25 19:44:29.061 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:44:29:INFO] 2019-04-25 19:44:29.131 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:44:29:INFO] 2019-04-25 19:44:29.132 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:44:29:INFO] 2019-04-25 19:44:29.380 ios_chrome_web_egtests[14908:240374] Attempting to type key t. [07:44:29:INFO] 2019-04-25 19:44:29.383 ios_chrome_web_egtests[14908:240374] Tapping on key: t. [07:44:29:INFO] 2019-04-25 19:44:29.602 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:44:29:INFO] 2019-04-25 19:44:29.602 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:44:29:INFO] 2019-04-25 19:44:29.666 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:44:29:INFO] 2019-04-25 19:44:29.667 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:44:29:INFO] 2019-04-25 19:44:29.892 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:44:29:INFO] 2019-04-25 19:44:29.893 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:44:30:INFO] 2019-04-25 19:44:30.078 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:44:30:INFO] 2019-04-25 19:44:30.081 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:44:34:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnSelectOmniboxSuggestion]' passed (12.610 seconds). [07:44:34:INFO] Test Suite 'CacheTestCase' passed at 2019-04-25 19:44:34.620. [07:44:34:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 24.534 (24.542) seconds [07:44:34:INFO] Test Suite 'ChildWindowOpenByDOMTestCase' started at 2019-04-25 19:44:34.621 [07:44:34:INFO] [0425/194434.622552:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:44:34:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithName]' started. [07:44:37:INFO] [0425/194437.299270:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:44:42:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithName]' passed (7.938 seconds). [07:44:42:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithoutName]' started. [07:44:51:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithoutName]' passed (8.804 seconds). [07:44:51:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testChildWindowsWithAndWithoutName]' started. [07:45:01:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testChildWindowsWithAndWithoutName]' passed (10.319 seconds). [07:45:01:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithName]' started. [07:45:10:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithName]' passed (8.493 seconds). [07:45:10:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithoutName]' started. [07:45:21:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithoutName]' passed (10.818 seconds). [07:45:21:INFO] Test Suite 'ChildWindowOpenByDOMTestCase' passed at 2019-04-25 19:45:21.002. [07:45:21:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 46.371 (46.381) seconds [07:45:21:INFO] Test Suite 'ErrorPageTestCase' started at 2019-04-25 19:45:21.003 [07:45:21:INFO] [0425/194521.004745:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:45:21:INFO] Test Case '-[ErrorPageTestCase testReloadErrorPage]' started. [07:45:23:INFO] [0425/194523.657120:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:45:28:INFO] Test Case '-[ErrorPageTestCase testReloadErrorPage]' passed (7.001 seconds). [07:45:28:INFO] Test Case '-[ErrorPageTestCase testReloadPageAfterServerIsDown]' started. [07:45:30:INFO] [0425/194530.719701:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:45:34:INFO] Test Case '-[ErrorPageTestCase testReloadPageAfterServerIsDown]' passed (6.847 seconds). [07:45:34:INFO] Test Suite 'ErrorPageTestCase' passed at 2019-04-25 19:45:34.862. [07:45:34:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 13.848 (13.859) seconds [07:45:34:INFO] Test Suite 'FormsTestCase' started at 2019-04-25 19:45:34.863 [07:45:34:INFO] [0425/194534.864627:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:45:34:INFO] Test Case '-[FormsTestCase testGoBackButtonAfterFormSubmission]' started. [07:45:37:INFO] [0425/194537.701024:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:45:42:INFO] Test Case '-[FormsTestCase testGoBackButtonAfterFormSubmission]' passed (7.961 seconds). [07:45:42:INFO] Test Case '-[FormsTestCase testPostFormEntryWithKeyboard]' started. [07:45:56:INFO] Test Case '-[FormsTestCase testPostFormEntryWithKeyboard]' passed (13.346 seconds). [07:45:56:INFO] Test Case '-[FormsTestCase testPostFormToSamePage]' started. [07:46:06:INFO] Test Case '-[FormsTestCase testPostFormToSamePage]' passed (10.024 seconds). [07:46:06:INFO] Test Case '-[FormsTestCase testRepostFormAfterIndexNavigation]' started. [07:46:21:INFO] Test Case '-[FormsTestCase testRepostFormAfterIndexNavigation]' passed (15.109 seconds). [07:46:21:INFO] Test Case '-[FormsTestCase testRepostFormAfterReload]' started. [07:46:32:INFO] Test Case '-[FormsTestCase testRepostFormAfterReload]' passed (10.934 seconds). [07:46:32:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBack]' started. [07:46:35:INFO] [0425/194635.891930:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:46:43:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBack]' passed (11.413 seconds). [07:46:43:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBackAndForward]' started. [07:46:55:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBackAndForward]' passed (11.355 seconds). [07:46:55:INFO] Test Case '-[FormsTestCase testRepostFormCancelling]' started. [07:47:05:INFO] Test Case '-[FormsTestCase testRepostFormCancelling]' passed (10.970 seconds). [07:47:05:INFO] Test Case '-[FormsTestCase testRepostFormCancellingAfterRedirect]' started. [07:47:13:INFO] Test Case '-[FormsTestCase testRepostFormCancellingAfterRedirect]' passed (7.641 seconds). [07:47:13:INFO] Test Case '-[FormsTestCase testRepostFormDismissedByNewNavigation]' started. [07:47:23:INFO] Test Case '-[FormsTestCase testRepostFormDismissedByNewNavigation]' passed (9.636 seconds). [07:47:23:INFO] Test Suite 'FormsTestCase' passed at 2019-04-25 19:47:23.265. [07:47:23:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 108.389 (108.403) seconds [07:47:23:INFO] Test Suite 'HTTPAuthTestCase' started at 2019-04-25 19:47:23.266 [07:47:23:INFO] [0425/194723.267989:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:47:23:INFO] Test Case '-[HTTPAuthTestCase testCancellingBasicAuth]' started. [07:47:29:INFO] Test Case '-[HTTPAuthTestCase testCancellingBasicAuth]' passed (6.417 seconds). [07:47:29:INFO] Test Case '-[HTTPAuthTestCase testSuccessfullBasicAuth]' started. [07:47:32:INFO] 2019-04-25 19:47:32.839 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:47:32:INFO] 2019-04-25 19:47:32.840 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:47:32:INFO] 2019-04-25 19:47:32.893 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:32:INFO] 2019-04-25 19:47:32.893 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:32:INFO] 2019-04-25 19:47:32.945 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:32:INFO] 2019-04-25 19:47:32.946 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:33:INFO] 2019-04-25 19:47:32.999 ios_chrome_web_egtests[14908:240374] Attempting to type key d. [07:47:33:INFO] 2019-04-25 19:47:33.000 ios_chrome_web_egtests[14908:240374] Tapping on key: d. [07:47:33:INFO] 2019-04-25 19:47:33.053 ios_chrome_web_egtests[14908:240374] Attempting to type key u. [07:47:33:INFO] 2019-04-25 19:47:33.054 ios_chrome_web_egtests[14908:240374] Tapping on key: u. [07:47:33:INFO] 2019-04-25 19:47:33.107 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:47:33:INFO] 2019-04-25 19:47:33.108 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:47:33:INFO] 2019-04-25 19:47:33.161 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:47:33:INFO] 2019-04-25 19:47:33.161 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:47:33:INFO] 2019-04-25 19:47:33.234 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:47:33:INFO] 2019-04-25 19:47:33.235 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:47:34:INFO] 2019-04-25 19:47:34.591 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:47:34:INFO] 2019-04-25 19:47:34.592 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:47:34:INFO] 2019-04-25 19:47:34.643 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:34:INFO] 2019-04-25 19:47:34.644 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:34:INFO] 2019-04-25 19:47:34.697 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:34:INFO] 2019-04-25 19:47:34.698 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:34:INFO] 2019-04-25 19:47:34.774 ios_chrome_web_egtests[14908:240374] Attempting to type key d. [07:47:34:INFO] 2019-04-25 19:47:34.775 ios_chrome_web_egtests[14908:240374] Tapping on key: d. [07:47:34:INFO] 2019-04-25 19:47:34.827 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:47:34:INFO] 2019-04-25 19:47:34.828 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:47:34:INFO] 2019-04-25 19:47:34.879 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:47:34:INFO] 2019-04-25 19:47:34.880 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:47:34:INFO] 2019-04-25 19:47:34.932 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:47:34:INFO] 2019-04-25 19:47:34.933 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:47:35:INFO] 2019-04-25 19:47:35.020 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:47:35:INFO] 2019-04-25 19:47:35.021 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:47:37:INFO] [0425/194737.056232:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:47:39:INFO] Test Case '-[HTTPAuthTestCase testSuccessfullBasicAuth]' passed (10.264 seconds). [07:47:39:INFO] Test Case '-[HTTPAuthTestCase testUnsuccessfullBasicAuth]' started. [07:47:43:INFO] 2019-04-25 19:47:43.256 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:47:43:INFO] 2019-04-25 19:47:43.259 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:47:43:INFO] 2019-04-25 19:47:43.312 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:43:INFO] 2019-04-25 19:47:43.313 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:43:INFO] 2019-04-25 19:47:43.364 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:43:INFO] 2019-04-25 19:47:43.365 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:43:INFO] 2019-04-25 19:47:43.417 ios_chrome_web_egtests[14908:240374] Attempting to type key d. [07:47:43:INFO] 2019-04-25 19:47:43.418 ios_chrome_web_egtests[14908:240374] Tapping on key: d. [07:47:43:INFO] 2019-04-25 19:47:43.470 ios_chrome_web_egtests[14908:240374] Attempting to type key u. [07:47:43:INFO] 2019-04-25 19:47:43.471 ios_chrome_web_egtests[14908:240374] Tapping on key: u. [07:47:43:INFO] 2019-04-25 19:47:43.523 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:47:43:INFO] 2019-04-25 19:47:43.524 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:47:43:INFO] 2019-04-25 19:47:43.605 ios_chrome_web_egtests[14908:240374] Attempting to type key e. [07:47:43:INFO] 2019-04-25 19:47:43.606 ios_chrome_web_egtests[14908:240374] Tapping on key: e. [07:47:43:INFO] 2019-04-25 19:47:43.691 ios_chrome_web_egtests[14908:240374] Attempting to type key r. [07:47:43:INFO] 2019-04-25 19:47:43.692 ios_chrome_web_egtests[14908:240374] Tapping on key: r. [07:47:45:INFO] 2019-04-25 19:47:45.019 ios_chrome_web_egtests[14908:240374] Attempting to type key g. [07:47:45:INFO] 2019-04-25 19:47:45.021 ios_chrome_web_egtests[14908:240374] Tapping on key: g. [07:47:45:INFO] 2019-04-25 19:47:45.074 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:45:INFO] 2019-04-25 19:47:45.075 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:45:INFO] 2019-04-25 19:47:45.128 ios_chrome_web_egtests[14908:240374] Attempting to type key o. [07:47:45:INFO] 2019-04-25 19:47:45.129 ios_chrome_web_egtests[14908:240374] Tapping on key: o. [07:47:45:INFO] 2019-04-25 19:47:45.208 ios_chrome_web_egtests[14908:240374] Attempting to type key d. [07:47:45:INFO] 2019-04-25 19:47:45.209 ios_chrome_web_egtests[14908:240374] Tapping on key: d. [07:47:45:INFO] 2019-04-25 19:47:45.262 ios_chrome_web_egtests[14908:240374] Attempting to type key p. [07:47:45:INFO] 2019-04-25 19:47:45.263 ios_chrome_web_egtests[14908:240374] Tapping on key: p. [07:47:45:INFO] 2019-04-25 19:47:45.316 ios_chrome_web_egtests[14908:240374] Attempting to type key a. [07:47:45:INFO] 2019-04-25 19:47:45.317 ios_chrome_web_egtests[14908:240374] Tapping on key: a. [07:47:45:INFO] 2019-04-25 19:47:45.370 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:47:45:INFO] 2019-04-25 19:47:45.370 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:47:45:INFO] 2019-04-25 19:47:45.457 ios_chrome_web_egtests[14908:240374] Attempting to type key s. [07:47:45:INFO] 2019-04-25 19:47:45.458 ios_chrome_web_egtests[14908:240374] Tapping on key: s. [07:47:51:INFO] Test Case '-[HTTPAuthTestCase testUnsuccessfullBasicAuth]' passed (11.255 seconds). [07:47:51:INFO] Test Suite 'HTTPAuthTestCase' passed at 2019-04-25 19:47:51.208. [07:47:51:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 27.935 (27.942) seconds [07:47:51:INFO] Test Suite 'JSPrintTestCase' started at 2019-04-25 19:47:51.209 [07:47:51:INFO] [0425/194751.210599:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:47:51:INFO] Test Case '-[JSPrintTestCase testWebPrintButton]' started. [07:47:53:INFO] [0425/194753.610366:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:47:55:INFO] 2019-04-25 19:47:55.190 ios_chrome_web_egtests[14908:240374] Unbalanced calls to begin/end appearance transitions for <UIViewController: 0x7f92a5624930>. [07:47:58:INFO] 2019-04-25 19:47:58.232 ios_chrome_web_egtests[14908:240374] Warning: Attempt to dismiss from view controller <UIViewController: 0x7f92a5624930> while a presentation or dismiss is in progress! [07:48:00:INFO] Test Case '-[JSPrintTestCase testWebPrintButton]' passed (9.703 seconds). [07:48:00:INFO] Test Suite 'JSPrintTestCase' passed at 2019-04-25 19:48:00.920. [07:48:00:INFO] Executed 1 test, with 0 failures (0 unexpected) in 9.703 (9.711) seconds [07:48:00:INFO] Test Suite 'NavigationTestCase' started at 2019-04-25 19:48:00.921 [07:48:00:INFO] [0425/194800.922381:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:48:00:INFO] Test Case '-[NavigationTestCase test301Redirect]' started. [07:48:03:INFO] [0425/194803.283615:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:48:13:INFO] Test Case '-[NavigationTestCase test301Redirect]' passed (12.581 seconds). [07:48:13:INFO] Test Case '-[NavigationTestCase testHistoryBackAndForwardAroundNTP]' started. [07:48:15:INFO] [0425/194815.848025:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:48:19:INFO] [0425/194819.404702:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:48:19:INFO] [0425/194819.766473:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:48:22:INFO] Test Case '-[NavigationTestCase testHistoryBackAndForwardAroundNTP]' passed (9.203 seconds). [07:48:22:INFO] Test Case '-[NavigationTestCase testHistoryBackNavigation]' started. [07:48:25:INFO] [0425/194825.099244:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:48:31:INFO] Test Case '-[NavigationTestCase testHistoryBackNavigation]' passed (8.881 seconds). [07:48:31:INFO] Test Case '-[NavigationTestCase testHistoryCrossDocumentLoad]' started. [07:48:33:INFO] [0425/194833.835029:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:48:39:INFO] Test Case '-[NavigationTestCase testHistoryCrossDocumentLoad]' passed (8.312 seconds). [07:48:39:INFO] Test Case '-[NavigationTestCase testHistoryForwardNavigation]' started. [07:48:42:INFO] [0425/194842.129387:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:48:48:INFO] Test Case '-[NavigationTestCase testHistoryForwardNavigation]' passed (8.740 seconds). [07:48:48:INFO] Test Case '-[NavigationTestCase testHistoryGoDelta]' started. [07:48:50:INFO] [0425/194850.900021:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:48:59:INFO] Test Case '-[NavigationTestCase testHistoryGoDelta]' passed (10.527 seconds). [07:48:59:INFO] Test Case '-[NavigationTestCase testHistoryGoDeltaZero]' started. [07:49:01:INFO] [0425/194901.654465:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:49:05:INFO] Test Case '-[NavigationTestCase testHistoryGoDeltaZero]' passed (6.442 seconds). [07:49:05:INFO] Test Case '-[NavigationTestCase testHistoryGoNoParameter]' started. [07:49:07:INFO] [0425/194907.939586:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:49:11:INFO] Test Case '-[NavigationTestCase testHistoryGoNoParameter]' passed (6.277 seconds). [07:49:11:INFO] Test Case '-[NavigationTestCase testHistoryGoOutOfBounds]' started. [07:49:14:INFO] [0425/194914.192412:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:49:19:INFO] Test Case '-[NavigationTestCase testHistoryGoOutOfBounds]' passed (7.238 seconds). [07:49:19:INFO] Test Case '-[NavigationTestCase testJavaScriptRedirect]' started. [07:49:21:INFO] [0425/194921.791522:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:49:29:INFO] Test Case '-[NavigationTestCase testJavaScriptRedirect]' passed (9.979 seconds). [07:49:29:INFO] Test Case '-[NavigationTestCase testRedirectRefresh]' started. [07:49:31:INFO] [0425/194931.626976:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:49:42:INFO] Test Case '-[NavigationTestCase testRedirectRefresh]' passed (12.973 seconds). [07:49:42:INFO] Test Case '-[NavigationTestCase testRedirectWindow]' started. [07:49:44:INFO] [0425/194944.726510:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:49:55:INFO] Test Case '-[NavigationTestCase testRedirectWindow]' passed (12.930 seconds). [07:49:55:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToNTPAndNavigateForward]' started. [07:49:57:INFO] [0425/194957.541251:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:50:03:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToNTPAndNavigateForward]' passed (8.665 seconds). [07:50:03:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToWebUIAndNavigateForward]' started. [07:50:07:INFO] [0425/195007.274911:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:50:15:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToWebUIAndNavigateForward]' passed (11.890 seconds). [07:50:15:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeHash]' started. [07:50:17:INFO] [0425/195017.948890:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:50:26:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeHash]' passed (10.681 seconds). [07:50:26:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeToSameHash]' started. [07:50:28:INFO] [0425/195028.843135:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:50:35:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeToSameHash]' passed (9.536 seconds). [07:50:35:INFO] Test Case '-[NavigationTestCase testWindowLocationReplaceAndChangeHash]' started. [07:50:38:INFO] [0425/195038.389153:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:50:44:INFO] Test Case '-[NavigationTestCase testWindowLocationReplaceAndChangeHash]' passed (9.130 seconds). [07:50:44:INFO] Test Suite 'NavigationTestCase' passed at 2019-04-25 19:50:44.925. [07:50:44:INFO] Executed 17 tests, with 0 failures (0 unexpected) in 163.986 (164.004) seconds [07:50:44:INFO] Test Suite 'ProgressIndicatorTestCase' started at 2019-04-25 19:50:44.926 [07:50:44:INFO] [0425/195044.928311:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:50:44:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterFormSubmit]' started. [07:50:47:INFO] [0425/195047.451866:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:50:51:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterFormSubmit]' passed (6.504 seconds). [07:50:51:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterSuppressedFormPost]' started. [07:50:57:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterSuppressedFormPost]' passed (6.398 seconds). [07:50:57:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShown]' started. [07:51:05:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShown]' passed (7.947 seconds). [07:51:05:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShownOnFormSubmit]' started. [07:51:14:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShownOnFormSubmit]' passed (8.769 seconds). [07:51:14:INFO] Test Suite 'ProgressIndicatorTestCase' passed at 2019-04-25 19:51:14.554. [07:51:14:INFO] Executed 4 tests, with 0 failures (0 unexpected) in 29.619 (29.628) seconds [07:51:14:INFO] Test Suite 'PushAndReplaceStateNavigationTestCase' started at 2019-04-25 19:51:14.555 [07:51:14:INFO] [0425/195114.556390:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:51:14:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryNavigatingPastNonPushedURL]' started. [07:51:16:INFO] [0425/195116.985095:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:51:31:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryNavigatingPastNonPushedURL]' passed (17.065 seconds). [07:51:31:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushStateThenGoBackAndForward]' started. [07:51:43:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushStateThenGoBackAndForward]' passed (11.790 seconds). [07:51:43:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushUnicodeCharacters]' started. [07:51:52:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushUnicodeCharacters]' passed (9.453 seconds). [07:51:52:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryReplaceStateThenGoBackAndForward]' started. [07:52:08:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryReplaceStateThenGoBackAndForward]' passed (15.648 seconds). [07:52:08:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryWithBase]' started. [07:52:14:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryWithBase]' passed (5.626 seconds). [07:52:14:INFO] Test Suite 'PushAndReplaceStateNavigationTestCase' passed at 2019-04-25 19:52:14.145. [07:52:14:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 59.583 (59.590) seconds [07:52:14:INFO] Test Suite 'StopLoadingTestCase' started at 2019-04-25 19:52:14.145 [07:52:14:INFO] [0425/195214.146852:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:52:14:INFO] Test Case '-[StopLoadingTestCase testStopLoading]' started. [07:52:27:INFO] Test Case '-[StopLoadingTestCase testStopLoading]' passed (13.811 seconds). [07:52:27:INFO] Test Suite 'StopLoadingTestCase' passed at 2019-04-25 19:52:27.961. [07:52:27:INFO] Executed 1 test, with 0 failures (0 unexpected) in 13.811 (13.816) seconds [07:52:27:INFO] Test Suite 'TabOrderTestCase' started at 2019-04-25 19:52:27.962 [07:52:27:INFO] [0425/195227.963701:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:52:27:INFO] Test Case '-[TabOrderTestCase testChildTabOrdering]' started. [07:52:30:INFO] [0425/195230.544027:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:52:56:INFO] Test Case '-[TabOrderTestCase testChildTabOrdering]' passed (28.719 seconds). [07:52:56:INFO] Test Suite 'TabOrderTestCase' passed at 2019-04-25 19:52:56.690. [07:52:56:INFO] Executed 1 test, with 0 failures (0 unexpected) in 28.719 (28.727) seconds [07:52:56:INFO] Test Suite 'VisibleURLTestCase' started at 2019-04-25 19:52:56.691 [07:52:56:INFO] [0425/195256.692687:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:52:56:INFO] Test Case '-[VisibleURLTestCase testBackForwardNavigation]' started. [07:52:59:INFO] [0425/195259.259102:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:53:11:INFO] Test Case '-[VisibleURLTestCase testBackForwardNavigation]' passed (14.560 seconds). [07:53:11:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingReload]' started. [07:53:24:INFO] [0425/195324.389127:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:53:27:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingReload]' passed (16.104 seconds). [07:53:27:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingRendererInitiatedNavigation]' started. [07:53:38:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingRendererInitiatedNavigation]' passed (10.640 seconds). [07:53:38:INFO] Test Case '-[VisibleURLTestCase testDoubleBackJSNavigation]' started. [07:53:48:INFO] Test Case '-[VisibleURLTestCase testDoubleBackJSNavigation]' passed (10.819 seconds). [07:53:48:INFO] Test Case '-[VisibleURLTestCase testDoubleBackNavigation]' started. [07:53:58:INFO] [0425/195358.483846:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:54:01:INFO] Test Case '-[VisibleURLTestCase testDoubleBackNavigation]' passed (12.624 seconds). [07:54:01:INFO] Test Case '-[VisibleURLTestCase testDoubleForwardNavigationToWebUIPage]' started. [07:54:13:INFO] Test Case '-[VisibleURLTestCase testDoubleForwardNavigationToWebUIPage]' passed (11.976 seconds). [07:54:13:INFO] Test Case '-[VisibleURLTestCase testHistoryNavigation]' started. [07:54:23:INFO] [0425/195423.706147:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:54:26:INFO] Test Case '-[VisibleURLTestCase testHistoryNavigation]' passed (13.231 seconds). [07:54:26:INFO] Test Case '-[VisibleURLTestCase testJSBackForwardNavigation]' started. [07:54:39:INFO] Test Case '-[VisibleURLTestCase testJSBackForwardNavigation]' passed (13.115 seconds). [07:54:39:INFO] Test Case '-[VisibleURLTestCase testJSGoNavigation]' started. [07:54:46:INFO] [0425/195446.758665:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:54:53:INFO] Test Case '-[VisibleURLTestCase testJSGoNavigation]' passed (13.292 seconds). [07:54:53:INFO] Test Case '-[VisibleURLTestCase testRendererInitiatedNavigationWithPendingBackNavigation]' started. [07:55:00:INFO] [0425/195500.569041:WARNING:web_state_impl.mm(661)] Script execution has failed: A JavaScript exception occurred [07:55:03:INFO] Test Case '-[VisibleURLTestCase testRendererInitiatedNavigationWithPendingBackNavigation]' passed (10.450 seconds). [07:55:03:INFO] Test Case '-[VisibleURLTestCase testStoppingPendingBackNavigationAndReload]' started. [07:55:23:INFO] Test Case '-[VisibleURLTestCase testStoppingPendingBackNavigationAndReload]' passed (20.018 seconds). [07:55:23:INFO] Test Suite 'VisibleURLTestCase' passed at 2019-04-25 19:55:23.542. [07:55:23:INFO] Executed 11 tests, with 0 failures (0 unexpected) in 146.829 (146.852) seconds [07:55:23:INFO] Test Suite 'WindowOpenByDOMTestCase' started at 2019-04-25 19:55:23.543 [07:55:23:INFO] [0425/195523.545508:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [07:55:23:INFO] Test Case '-[WindowOpenByDOMTestCase testBlockPopupInjectedIntoOpenedWindow]' started. [07:55:26:INFO] [0425/195526.136885:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [07:55:32:INFO] Test Case '-[WindowOpenByDOMTestCase testBlockPopupInjectedIntoOpenedWindow]' passed (9.049 seconds). [07:55:32:INFO] Test Case '-[WindowOpenByDOMTestCase testCloseWindowNotOpenByDOM]' started. [07:55:38:INFO] Test Case '-[WindowOpenByDOMTestCase testCloseWindowNotOpenByDOM]' passed (5.549 seconds). [07:55:38:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTarget]' started. [07:55:46:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTarget]' passed (8.469 seconds). [07:55:46:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetMultipleTimes]' started. [07:56:00:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetMultipleTimes]' passed (14.325 seconds). [07:56:00:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetSessionStorage]' started. [07:56:09:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetSessionStorage]' passed (8.365 seconds). [07:56:09:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithDelayedClose]' started. [07:56:18:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithDelayedClose]' passed (9.643 seconds). [07:56:18:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithImmediateClose]' started. [07:56:25:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithImmediateClose]' passed (6.541 seconds). [07:56:25:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithoutUserGesture]' started. [07:56:32:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithoutUserGesture]' passed (6.514 seconds). [07:56:32:INFO] Test Case '-[WindowOpenByDOMTestCase testLocationReplaceInWindowOpenWithEmptyTarget]' started. [07:56:39:INFO] Test Case '-[WindowOpenByDOMTestCase testLocationReplaceInWindowOpenWithEmptyTarget]' passed (7.948 seconds). [07:56:39:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndAssignToHref]' started. [07:56:48:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndAssignToHref]' passed (8.113 seconds). [07:56:48:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndCallLocationAssign]' started. [07:56:54:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndCallLocationAssign]' passed (6.865 seconds). [07:56:54:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndSetLocation]' started. [07:57:01:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndSetLocation]' passed (6.524 seconds). [07:57:01:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithBlankTarget]' started. [07:57:08:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithBlankTarget]' passed (7.202 seconds). [07:57:08:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithButtonOnClick]' started. [07:57:15:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithButtonOnClick]' passed (6.984 seconds). [07:57:15:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithEmptyTarget]' started. [07:57:22:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithEmptyTarget]' passed (7.303 seconds). [07:57:22:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithMetaRefresh]' started. [07:57:30:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithMetaRefresh]' passed (7.407 seconds). [07:57:30:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithPreventDefaultLink]' started. [07:57:37:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithPreventDefaultLink]' passed (7.620 seconds). [07:57:37:INFO] Test Suite 'WindowOpenByDOMTestCase' passed at 2019-04-25 19:57:37.989. [07:57:37:INFO] Executed 17 tests, with 0 failures (0 unexpected) in 134.421 (134.445) seconds [07:57:37:INFO] Test Suite 'ios_chrome_web_egtests.app' passed at 2019-04-25 19:57:37.990. [07:57:37:INFO] Executed 94 tests, with 0 failures (0 unexpected) in 913.622 (915.668) seconds [07:57:37:INFO] Test Suite 'All tests' passed at 2019-04-25 19:57:37.992. [07:57:37:INFO] Executed 94 tests, with 0 failures (0 unexpected) in 913.622 (915.670) seconds [07:57:38:INFO] [07:57:38:INFO] [07:57:38:INFO] Test session results and logs: [07:57:38:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-ebcbniioggodgictkcjotqyfdsbv/Logs/Test/Test-Transient Testing-2019.04.25_19-41-46--0700.xcresult [07:57:38:INFO] [07:57:38:INFO] 2019-04-25 19:57:38.319 xcodebuild[14789:238783] [MT] IDETestOperationsObserverDebug: 951.470 elapsed -- Testing started completed. [07:57:38:INFO] 2019-04-25 19:57:38.319 xcodebuild[14789:238783] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [07:57:38:INFO] 2019-04-25 19:57:38.319 xcodebuild[14789:238783] [MT] IDETestOperationsObserverDebug: 951.470 sec, +951.470 sec -- end [07:57:38:INFO] ** TEST EXECUTE SUCCEEDED ** [07:57:38:INFO] [07:57:42:DEBUG] Finished print_process_output. [07:57:42:INFO] Waiting for test process to terminate. [07:57:42:INFO] Test process terminated. [07:57:42:DEBUG] Setting sigterm handler. [07:57:42:DEBUG] Stdout flushed after test process. [07:57:42:DEBUG] Processing test results. [07:57:42:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0 [07:57:42:DEBUG] Extracting test data. [07:57:42:DEBUG] Retrieving crash reports. [07:57:42:DEBUG] Retrieving derived data. [07:57:42:DEBUG] Making desktop screenshots. [07:57:43:DEBUG] Killing simulators. [07:57:48:DEBUG] Wiping simulator. Device wiped. [07:57:51:DEBUG] End of tear_down. 2019-04-25 20:00:14,926 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/30DKnBCr/4470562e1fcf1b10'] 2019-04-25 20:00:14,927 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/30DKnBCr/4470562e1fcf1b10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/30DKnBCr/4470562e1fcf1b10/output.json'] 2019-04-25 20:00:14,927 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/30DKnBCr' Found ['/b/s/w/ir/k/recipe_cleanup/30DKnBCr/4470562e1fcf1b10'] 2019-04-25 20:00:14,927 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/30DKnBCr/4470562e1fcf1b10/output.json'] 2019-04-25 20:00:14,928 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpzPmFbw.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/30DKnBCr -o /b/s/w/ir/tmp/t/tmptPLOHt.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpzPmFbw.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/30DKnBCr', '-o', '/b/s/w/ir/tmp/t/tmptPLOHt.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpzPmFbw.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/30DKnBCr', '-o', '/b/s/w/ir/tmp/t/tmptPLOHt.json'] returned exit code 0