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

{ "shards": [ { "bot_dimensions": [ { "key": "caches", "value": [ "swarming_module_cache_vpython", "xcode_ios_10b61", "xcode_ios_10l232m", "xcode_ios_10o45e" ] }, { "key": "cores", "value": [ "4" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-i7-4578U" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "8086", "8086:0a2e" ] }, { "key": "hidpi", "value": [ "0" ] }, { "key": "id", "value": [ "build787-m4" ] }, { "key": "mac_model", "value": [ "Macmini7,1" ] }, { "key": "machine_type", "value": [ "n1-standard-4" ] }, { "key": "os", "value": [ "Mac", "Mac-10.13", "Mac-10.13.6" ] }, { "key": "pool", "value": [ "Chrome" ] }, { "key": "python", "value": [ "2.7.10" ] }, { "key": "server_version", "value": [ "4211-90dafba" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-4" ] } ], "bot_id": "build787-m4", "bot_version": "a1949c9c9626232734f8c6a011db49db86c0d07bdcf5444a07f04046abeaa3cd", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/mac-amd64", "version": "1vQ99wQk82cRyVYu18rqMpXtb7XZ3WHvL3HFXTcLHN4C" }, "packages": [ { "package_name": "infra/tools/mac_toolchain/mac-amd64", "path": ".", "version": "1065f97353165dfafa89b64bfdaf9b424d705bc7" }, { "package_name": "infra/python/cpython/mac-amd64", "path": ".swarming_module", "version": "6dd10e31dc5d4cbb3c8f42a6fbd9485aeeb9ef0c" }, { "package_name": "infra/tools/luci/logdog/butler/mac-amd64", "path": ".swarming_module", "version": "3138aa2054ab30eac6f7e1e589e590571ae44692" }, { "package_name": "infra/tools/luci/vpython-native/mac-amd64", "path": ".swarming_module", "version": "LH2D9eSe-sJImw8pxSsKdnKz9LueOAM77FFBJMvP8BwC" }, { "package_name": "infra/tools/luci/vpython/mac-amd64", "path": ".swarming_module", "version": "yhDQeJa8hj64DkPAcLKVNQmNTgYsirl5_wvmnHvOz9cC" } ] }, "completed_ts": "2019-03-21T01:59:02.771540", "costs_usd": [ 0.10997358373154126 ], "created_ts": "2019-03-20T23:38:37.649201", "duration": 844.5428471565247, "modified_ts": "2019-03-21T01:59:02.771540", "name": "ios_chrome_ui_egtests (iPhone X iOS 11.4) shard 1/Mac-10.13.6/9e92b3af88/ios12-sdk-simulator/6708", "output": "[I 2019-03-20 18:44:31] About to install Xcode 10b61 in Xcode.app for ios\n[06:44:32:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\n[06:44:38:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/io1rdEb4/run_1553132678.xctestrun -destination platform=iOS Simulator,OS=11.4,name=iPhone X -resultBundlePath /b/s/w/io1rdEb4/11.4 iPhone X/attempt_0]\n[06:44:38:INFO] User defaults from command line:\nErasing all simulators.\nLaunching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/io1rdEb4/run_1553132678.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPhone X', '-resultBundlePath', '/b/s/w/io1rdEb4/11.4 iPhone X/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itYTk2MW', 'LOGNAME': 'chrome-bot', 'USER': 'chrome-bot', 'HOME': '/Users/chrome-bot', 'BOTO_CONFIG': '/Users/chrome-bot/.boto', 'PATH': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90/bin:/b/s/w/ir/.swarming_module:/b/s/w/ir/.swarming_module/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', 'SWARMING_BOT_ID': 'build787-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jiMQywSmhc/Render', 'SWARMING_SERVER': 'https://chromium-swarm.appspot.com', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'SWARMING_HEADLESS': '1', 'PYTHONNOUSERSITE': '1', 'LUCI_CONTEXT': '/b/s/w/itYTk2MW/luci_ctx.VHnNFL.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HwHAuuQb0G/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43b64a16e0a0b011', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itYTk2MW', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'}\n[06:44:38:INFO] IDEBuildOperationResultBundlePath = /b/s/w/io1rdEb4/11.4 iPhone X/attempt_0\n[06:44:38:INFO] IDETestRunSpecificationPath = /b/s/w/io1rdEb4/run_1553132678.xctestrun\n[06:44:38:INFO] \n[06:44:39:INFO] Testing started on 'iPhone X'\n[06:44:39:INFO] 2019-03-20 18:44:39.229 xcodebuild[13535:471535] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[06:44:39:INFO] /b/s/w/io1rdEb4/11.4 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-46DB2ABD-EBFE-4A02-8FE9-1AAF8D6ECBA5/ios_chrome_ui_egtests_module-C48A160A-7127-42D2-AABF-90E2E850F5DE/Session-ios_chrome_ui_egtests_module-2019-03-20_184439-BO59OZ.log\n[06:44:39:INFO] 2019-03-20 18:44:39.229 xcodebuild[13535:471481] [MT] IDETestOperationsObserverDebug: (18809427-987D-40AA-85F0-E37A9946EB16) Beginning test session ios_chrome_ui_egtests_module-18809427-987D-40AA-85F0-E37A9946EB16 at 2019-03-20 18:44:39.229 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fb838862320> {\n[06:44:39:INFO] \t\tSimDevice: iPhone X (483837AB-A39F-4819-B289-D50D32A48D7C, iOS 11.4, Shutdown)\n[06:44:39:INFO] } (11.4 (15F79))\n[06:45:04:INFO] 2019-03-20 18:45:04.573 ios_chrome_ui_egtests[13658:473122] Crash handler setup started.\n[06:45:04:INFO] 2019-03-20 18:45:04.575 ios_chrome_ui_egtests[13658:473122] Crash handler setup completed.\n[06:45:04:INFO] 2019-03-20 18:45:04.575 ios_chrome_ui_egtests[13658:473122] Enabling accessibility for automation on Simulator.\n[06:45:04:INFO] objc[13658]: 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 (0x14b9064d0) 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 (0x14b71ce38). One of the two will be used. Which one is undefined.\n[06:45:08:INFO] 2019-03-20 18:45:08.009 ios_chrome_ui_egtests[13658:473122] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fe5e6898a00>.\n[06:45:09:INFO] [0320/184509.623503:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/483837AB-A39F-4819-B289-D50D32A48D7C/data/Containers/Data/Application/61E8059A-7E85-402A-8EE0-41E8AB3A7378/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/483837AB-A39F-4819-B289-D50D32A48D7C/data/Containers/Data/Application/61E8059A-7E85-402A-8EE0-41E8AB3A7378/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[06:45:15:INFO] Test Suite 'Selected tests' started at 2019-03-20 18:45:15.406\n[06:45:15:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' started at 2019-03-20 18:45:15.407\n[06:45:15:INFO] Test Suite 'FirstRunTestCase' started at 2019-03-20 18:45:15.407\n[06:45:15:INFO] [0320/184515.416296:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:45:17:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' started.\n[06:45:20:INFO] 2019-03-20 18:45:20.389 ios_chrome_ui_egtests[13658:473122] Button touch target does not meet minimum size guidlines of (48, 48). Button: <PrimaryActionButton: 0x7fe5e4db5080; baseClass = UIButton; frame = (103 710; 169 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x6000006ea100>>, Touch Target: {169, 36}\n[06:45:26:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' passed (9.192 seconds).\n[06:45:26:INFO] Test Case '-[FirstRunTestCase testPrivacy]' started.\n[06:45:33:INFO] Test Case '-[FirstRunTestCase testPrivacy]' passed (7.401 seconds).\n[06:45:33:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' started.\n[06:45:37:INFO] [0320/184537.979218:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[06:45:38:INFO] [0320/184538.024626:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[06:45:38:INFO] [0320/184538.096712:ERROR:account_tracker.cc(264)] OnOAuthError\n[06:45:38:INFO] [0320/184538.096864:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[06:45:42:INFO] [0320/184542.164741:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[06:45:43:INFO] [0320/184543.865752:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[06:45:45:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' passed (11.939 seconds).\n[06:45:45:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' started.\n[06:45:49:INFO] [0320/184549.930525:ERROR:account_tracker.cc(264)] OnOAuthError\n[06:45:49:INFO] [0320/184549.930788:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[06:45:50:INFO] [0320/184550.979779:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[06:45:55:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' passed (9.835 seconds).\n[06:45:55:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' started.\n[06:46:03:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' passed (7.392 seconds).\n[06:46:03:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' started.\n[06:46:09:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' passed (6.469 seconds).\n[06:46:09:INFO] Test Suite 'FirstRunTestCase' passed at 2019-03-20 18:46:09.505.\n[06:46:09:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 52.227 (54.098) seconds\n[06:46:09:INFO] Test Suite 'FullscreenTestCase' started at 2019-03-20 18:46:09.506\n[06:46:09:INFO] [0320/184609.507088:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:46:09:INFO] Test Case '-[FullscreenTestCase testChromeToChromeURLKeepsHeaderOnScreen]' started.\n[06:46:18:INFO] Test Case '-[FullscreenTestCase testChromeToChromeURLKeepsHeaderOnScreen]' passed (9.446 seconds).\n[06:46:18:INFO] Test Case '-[FullscreenTestCase testHideHeaderUserScrollLongPage]' started.\n[06:46:20:INFO] [0320/184620.964623:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:46:27:INFO] Test Case '-[FullscreenTestCase testHideHeaderUserScrollLongPage]' passed (8.356 seconds).\n[06:46:27:INFO] Test Case '-[FullscreenTestCase testLongPDFInitialState]' started.\n[06:46:32:INFO] Test Case '-[FullscreenTestCase testLongPDFInitialState]' passed (5.527 seconds).\n[06:46:32:INFO] Test Case '-[FullscreenTestCase testLongPDFScroll]' started.\n[06:46:34:INFO] 2019-03-20 18:46:34.668 ios_chrome_ui_egtests[13658:473122] -- Earl Grey Test Disabled -- Disabled on iOS 11.\n[06:46:36:INFO] Test Case '-[FullscreenTestCase testLongPDFScroll]' passed (3.681 seconds).\n[06:46:36:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnErrorPage]' started.\n[06:46:44:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnErrorPage]' passed (7.876 seconds).\n[06:46:44:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnNativePageLoad]' started.\n[06:46:52:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnNativePageLoad]' passed (7.931 seconds).\n[06:46:52:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnRegularPageLoad]' started.\n[06:47:02:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnRegularPageLoad]' passed (10.604 seconds).\n[06:47:02:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnReload]' started.\n[06:47:10:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnReload]' passed (7.715 seconds).\n[06:47:10:INFO] Test Case '-[FullscreenTestCase testShowHeaderWhenChildTabCloses]' started.\n[06:47:15:INFO] [0320/184715.325943:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[06:47:20:INFO] Test Case '-[FullscreenTestCase testShowHeaderWhenChildTabCloses]' passed (9.864 seconds).\n[06:47:20:INFO] Test Case '-[FullscreenTestCase testSmallWidePDFScroll]' started.\n[06:47:28:INFO] Test Case '-[FullscreenTestCase testSmallWidePDFScroll]' passed (8.017 seconds).\n[06:47:28:INFO] Test Suite 'FullscreenTestCase' passed at 2019-03-20 18:47:28.542.\n[06:47:28:INFO] \t Executed 10 tests, with 0 failures (0 unexpected) in 79.018 (79.036) seconds\n[06:47:28:INFO] Test Suite 'HistoryUITestCase' started at 2019-03-20 18:47:28.542\n[06:47:28:INFO] [0320/184728.543623:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:47:28:INFO] Test Case '-[HistoryUITestCase testAccessibilityOnHistory]' started.\n[06:47:30:INFO] [0320/184730.939699:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:47:42:INFO] Test Case '-[HistoryUITestCase testAccessibilityOnHistory]' passed (13.516 seconds).\n[06:47:42:INFO] Test Case '-[HistoryUITestCase testClearBrowsingHistory]' started.\n[06:48:04:INFO] Test Case '-[HistoryUITestCase testClearBrowsingHistory]' passed (22.154 seconds).\n[06:48:04:INFO] Test Case '-[HistoryUITestCase testContextMenuCopy]' started.\n[06:48:16:INFO] 2019-03-20 18:48:16.485 ios_chrome_ui_egtests[13658:473154] PBItemCollectionServicer connection disconnected.\n[06:48:19:INFO] Test Case '-[HistoryUITestCase testContextMenuCopy]' passed (15.702 seconds).\n[06:48:19:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]' started.\n[06:48:36:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]' passed (16.425 seconds).\n[06:48:36:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewTab]' started.\n[06:48:52:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewTab]' passed (16.365 seconds).\n[06:48:52:INFO] Test Case '-[HistoryUITestCase testDeleteHistory]' started.\n[06:49:11:INFO] Test Case '-[HistoryUITestCase testDeleteHistory]' passed (18.411 seconds).\n[06:49:11:INFO] Test Case '-[HistoryUITestCase testDisplayHistory]' started.\n[06:49:25:INFO] Test Case '-[HistoryUITestCase testDisplayHistory]' passed (14.167 seconds).\n[06:49:25:INFO] Test Case '-[HistoryUITestCase testDisplayNoHistory]' started.\n[06:49:35:INFO] Test Case '-[HistoryUITestCase testDisplayNoHistory]' passed (10.188 seconds).\n[06:49:35:INFO] Test Case '-[HistoryUITestCase testHistoryUpdateAfterBackNavigation]' started.\n[06:49:49:INFO] Test Case '-[HistoryUITestCase testHistoryUpdateAfterBackNavigation]' passed (13.875 seconds).\n[06:49:49:INFO] Test Case '-[HistoryUITestCase testSearchHistory]' started.\n[06:49:59:INFO] 2019-03-20 18:49:59.434 ios_chrome_ui_egtests[13658:473122] +[CATransaction synchronize] called within transaction\n[06:49:59:INFO] 2019-03-20 18:49:59.452 ios_chrome_ui_egtests[13658:473122] +[CATransaction synchronize] called within transaction\n[06:49:59:INFO] 2019-03-20 18:49:59.453 ios_chrome_ui_egtests[13658:473122] +[CATransaction synchronize] called within transaction\n[06:49:59:INFO] 2019-03-20 18:49:59.453 ios_chrome_ui_egtests[13658:473122] +[CATransaction synchronize] called within transaction\n[06:50:00:INFO] 2019-03-20 18:50:00.160 ios_chrome_ui_egtests[13658:473122] Attempting to type key /.\n[06:50:00:INFO] 2019-03-20 18:50:00.367 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, numbers.\n[06:50:00:INFO] 2019-03-20 18:50:00.453 ios_chrome_ui_egtests[13658:473122] Tapping on key: /.\n[06:50:00:INFO] 2019-03-20 18:50:00.529 ios_chrome_ui_egtests[13658:473122] Attempting to type key f.\n[06:50:00:INFO] 2019-03-20 18:50:00.833 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, letters.\n[06:50:00:INFO] 2019-03-20 18:50:00.886 ios_chrome_ui_egtests[13658:473122] Tapping on key: f.\n[06:50:00:INFO] 2019-03-20 18:50:00.939 ios_chrome_ui_egtests[13658:473122] Attempting to type key i.\n[06:50:00:INFO] 2019-03-20 18:50:00.939 ios_chrome_ui_egtests[13658:473122] Tapping on key: i.\n[06:50:00:INFO] 2019-03-20 18:50:00.993 ios_chrome_ui_egtests[13658:473122] Attempting to type key r.\n[06:50:00:INFO] 2019-03-20 18:50:00.993 ios_chrome_ui_egtests[13658:473122] Tapping on key: r.\n[06:50:01:INFO] 2019-03-20 18:50:01.049 ios_chrome_ui_egtests[13658:473122] Attempting to type key s.\n[06:50:01:INFO] 2019-03-20 18:50:01.050 ios_chrome_ui_egtests[13658:473122] Tapping on key: s.\n[06:50:01:INFO] 2019-03-20 18:50:01.100 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:50:01:INFO] 2019-03-20 18:50:01.101 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:50:01:INFO] 2019-03-20 18:50:01.153 ios_chrome_ui_egtests[13658:473122] Attempting to type key u.\n[06:50:01:INFO] 2019-03-20 18:50:01.153 ios_chrome_ui_egtests[13658:473122] Tapping on key: u.\n[06:50:01:INFO] 2019-03-20 18:50:01.206 ios_chrome_ui_egtests[13658:473122] Attempting to type key r.\n[06:50:01:INFO] 2019-03-20 18:50:01.207 ios_chrome_ui_egtests[13658:473122] Tapping on key: r.\n[06:50:01:INFO] 2019-03-20 18:50:01.258 ios_chrome_ui_egtests[13658:473122] Attempting to type key l.\n[06:50:01:INFO] 2019-03-20 18:50:01.259 ios_chrome_ui_egtests[13658:473122] Tapping on key: l.\n[06:50:01:INFO] 2019-03-20 18:50:01.310 ios_chrome_ui_egtests[13658:473122] Attempting to type key /.\n[06:50:01:INFO] 2019-03-20 18:50:01.412 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, numbers.\n[06:50:01:INFO] 2019-03-20 18:50:01.466 ios_chrome_ui_egtests[13658:473122] Tapping on key: /.\n[06:50:05:INFO] Test Case '-[HistoryUITestCase testSearchHistory]' passed (16.445 seconds).\n[06:50:05:INFO] Test Case '-[HistoryUITestCase testSearchLongPressOnScrimCancelsSearchController]' started.\n[06:50:23:INFO] Test Case '-[HistoryUITestCase testSearchLongPressOnScrimCancelsSearchController]' passed (17.352 seconds).\n[06:50:23:INFO] Test Suite 'HistoryUITestCase' passed at 2019-03-20 18:50:23.156.\n[06:50:23:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 174.601 (174.614) seconds\n[06:50:23:INFO] Test Suite 'InfobarTestCase' started at 2019-03-20 18:50:23.157\n[06:50:23:INFO] [0320/185023.158230:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:50:23:INFO] Test Case '-[InfobarTestCase testInfobarButtonDismissal]' started.\n[06:50:25:INFO] [0320/185025.238357:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:50:32:INFO] Test Case '-[InfobarTestCase testInfobarButtonDismissal]' passed (9.573 seconds).\n[06:50:32:INFO] Test Case '-[InfobarTestCase testInfobarsDismissOnNavigate]' started.\n[06:50:42:INFO] Test Case '-[InfobarTestCase testInfobarsDismissOnNavigate]' passed (10.221 seconds).\n[06:50:42:INFO] Test Case '-[InfobarTestCase testInfobarTabSwitch]' started.\n[06:51:00:INFO] Test Case '-[InfobarTestCase testInfobarTabSwitch]' passed (17.617 seconds).\n[06:51:00:INFO] Test Case '-[InfobarTestCase testInfobarTallerLayout]' started.\n[06:51:10:INFO] Test Case '-[InfobarTestCase testInfobarTallerLayout]' passed (9.783 seconds).\n[06:51:10:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisible]' started.\n[06:51:23:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisible]' passed (13.413 seconds).\n[06:51:23:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisibleHeight]' started.\n[06:51:42:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisibleHeight]' passed (18.628 seconds).\n[06:51:42:INFO] Test Suite 'InfobarTestCase' passed at 2019-03-20 18:51:42.400.\n[06:51:42:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 79.235 (79.243) seconds\n[06:51:42:INFO] Test Suite 'InspectUITestCase' started at 2019-03-20 18:51:42.400\n[06:51:42:INFO] [0320/185142.401657:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:51:42:INFO] Test Case '-[InspectUITestCase testIframeLogging]' started.\n[06:51:47:INFO] [0320/185147.226468:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:51:50:INFO] Test Case '-[InspectUITestCase testIframeLogging]' passed (8.035 seconds).\n[06:51:50:INFO] Test Case '-[InspectUITestCase testLoggingFromMultipleTabs]' started.\n[06:51:55:INFO] [0320/185155.210951:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:52:01:INFO] Test Case '-[InspectUITestCase testLoggingFromMultipleTabs]' passed (10.917 seconds).\n[06:52:01:INFO] Test Case '-[InspectUITestCase testMainFrameLogging]' started.\n[06:52:06:INFO] [0320/185206.092553:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:52:09:INFO] Test Case '-[InspectUITestCase testMainFrameLogging]' passed (7.984 seconds).\n[06:52:09:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnReload]' started.\n[06:52:14:INFO] [0320/185214.079736:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:52:18:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnReload]' passed (8.975 seconds).\n[06:52:18:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnStopLogging]' started.\n[06:52:23:INFO] [0320/185223.078506:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:52:26:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnStopLogging]' passed (7.938 seconds).\n[06:52:26:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnTabClosure]' started.\n[06:52:31:INFO] [0320/185231.002719:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:52:34:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnTabClosure]' passed (8.170 seconds).\n[06:52:34:INFO] Test Case '-[InspectUITestCase testStartStopLogging]' started.\n[06:52:39:INFO] Test Case '-[InspectUITestCase testStartStopLogging]' passed (4.974 seconds).\n[06:52:39:INFO] Test Suite 'InspectUITestCase' passed at 2019-03-20 18:52:39.402.\n[06:52:39:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 56.993 (57.001) seconds\n[06:52:39:INFO] Test Suite 'JavaScriptDialogTestCase' started at 2019-03-20 18:52:39.402\n[06:52:39:INFO] [0320/185239.403543:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:52:39:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterNewTabAnimation]' started.\n[06:52:41:INFO] [0320/185241.526310:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:52:50:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterNewTabAnimation]' passed (11.569 seconds).\n[06:52:50:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterShareMenu]' started.\n[06:52:52:INFO] 2019-03-20 18:52:52.851 ios_chrome_ui_egtests[13658:473122] -- Earl Grey Test Disabled -- Disabled until EG can use share menu.\n[06:52:54:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterShareMenu]' passed (3.816 seconds).\n[06:52:54:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlert]' started.\n[06:52:56:INFO] [0320/185256.860037:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:01:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlert]' passed (6.662 seconds).\n[06:53:01:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlertLoop]' started.\n[06:53:03:INFO] [0320/185303.522761:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:10:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlertLoop]' passed (9.064 seconds).\n[06:53:10:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptBehindSettings]' started.\n[06:53:12:INFO] [0320/185312.603465:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:23:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptBehindSettings]' passed (12.738 seconds).\n[06:53:23:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationCancelled]' started.\n[06:53:25:INFO] [0320/185325.340922:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:29:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationCancelled]' passed (6.682 seconds).\n[06:53:29:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationOK]' started.\n[06:53:32:INFO] [0320/185332.012446:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:36:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationOK]' passed (6.663 seconds).\n[06:53:36:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptCancelled]' started.\n[06:53:38:INFO] [0320/185338.675013:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:41:INFO] 2019-03-20 18:53:41.859 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:53:41:INFO] 2019-03-20 18:53:41.860 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:53:41:INFO] 2019-03-20 18:53:41.911 ios_chrome_ui_egtests[13658:473122] Attempting to type key e.\n[06:53:41:INFO] 2019-03-20 18:53:41.911 ios_chrome_ui_egtests[13658:473122] Tapping on key: e.\n[06:53:41:INFO] 2019-03-20 18:53:41.962 ios_chrome_ui_egtests[13658:473122] Attempting to type key s.\n[06:53:41:INFO] 2019-03-20 18:53:41.963 ios_chrome_ui_egtests[13658:473122] Tapping on key: s.\n[06:53:42:INFO] 2019-03-20 18:53:42.015 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:53:42:INFO] 2019-03-20 18:53:42.015 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:53:45:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptCancelled]' passed (8.986 seconds).\n[06:53:45:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptOK]' started.\n[06:53:47:INFO] [0320/185347.666095:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:53:50:INFO] 2019-03-20 18:53:50.905 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:53:50:INFO] 2019-03-20 18:53:50.906 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:53:50:INFO] 2019-03-20 18:53:50.959 ios_chrome_ui_egtests[13658:473122] Attempting to type key e.\n[06:53:50:INFO] 2019-03-20 18:53:50.959 ios_chrome_ui_egtests[13658:473122] Tapping on key: e.\n[06:53:51:INFO] 2019-03-20 18:53:51.011 ios_chrome_ui_egtests[13658:473122] Attempting to type key s.\n[06:53:51:INFO] 2019-03-20 18:53:51.011 ios_chrome_ui_egtests[13658:473122] Tapping on key: s.\n[06:53:51:INFO] 2019-03-20 18:53:51.064 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:53:51:INFO] 2019-03-20 18:53:51.065 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:53:54:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptOK]' passed (9.066 seconds).\n[06:53:54:INFO] Test Suite 'JavaScriptDialogTestCase' passed at 2019-03-20 18:53:54.657.\n[06:53:54:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 75.245 (75.254) seconds\n[06:53:54:INFO] Test Suite 'KeyboardCommandsTestCase' started at 2019-03-20 18:53:54.657\n[06:53:54:INFO] [0320/185354.658561:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:53:54:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommands_RecentTabsPresented]' started.\n[06:54:04:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommands_RecentTabsPresented]' passed (10.117 seconds).\n[06:54:04:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_AddBookmarkPresented]' started.\n[06:54:06:INFO] [0320/185406.782635:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:54:19:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_AddBookmarkPresented]' passed (14.821 seconds).\n[06:54:19:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_BookmarksPresented]' started.\n[06:54:29:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_BookmarksPresented]' passed (10.059 seconds).\n[06:54:29:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_SettingsPresented]' started.\n[06:54:39:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_SettingsPresented]' passed (10.099 seconds).\n[06:54:39:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsRegistered]' started.\n[06:54:43:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsRegistered]' passed (4.043 seconds).\n[06:54:43:INFO] Test Case '-[KeyboardCommandsTestCase testWebViewIsFirstResponderUponKeyPress]' started.\n[06:54:49:INFO] Test Case '-[KeyboardCommandsTestCase testWebViewIsFirstResponderUponKeyPress]' passed (5.207 seconds).\n[06:54:49:INFO] Test Suite 'KeyboardCommandsTestCase' passed at 2019-03-20 18:54:49.011.\n[06:54:49:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 54.346 (54.353) seconds\n[06:54:49:INFO] Test Suite 'NTPHomeTestCase' started at 2019-03-20 18:54:49.011\n[06:54:49:INFO] [0320/185449.012603:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[06:54:49:INFO] Test Case '-[NTPHomeTestCase testAccessibility]' started.\n[06:54:52:INFO] Test Case '-[NTPHomeTestCase testAccessibility]' passed (3.901 seconds).\n[06:54:52:INFO] Test Case '-[NTPHomeTestCase testCollectionShortcuts]' started.\n[06:55:14:INFO] Test Case '-[NTPHomeTestCase testCollectionShortcuts]' passed (21.487 seconds).\n[06:55:14:INFO] Test Case '-[NTPHomeTestCase testFavicons]' started.\n[06:55:40:INFO] Test Case '-[NTPHomeTestCase testFavicons]' passed (26.446 seconds).\n[06:55:40:INFO] Test Case '-[NTPHomeTestCase testOmniboxPinnedWidthRotation]' started.\n[06:55:47:INFO] Test Case '-[NTPHomeTestCase testOmniboxPinnedWidthRotation]' passed (6.386 seconds).\n[06:55:47:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotation]' started.\n[06:55:51:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotation]' passed (4.682 seconds).\n[06:55:51:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotationBehindSettings]' started.\n[06:56:03:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotationBehindSettings]' passed (11.229 seconds).\n[06:56:03:INFO] Test Case '-[NTPHomeTestCase testOpeningNewTab]' started.\n[06:56:17:INFO] Test Case '-[NTPHomeTestCase testOpeningNewTab]' passed (13.868 seconds).\n[06:56:17:INFO] Test Case '-[NTPHomeTestCase testOpenMultipleTabs]' started.\n[06:57:02:INFO] Test Case '-[NTPHomeTestCase testOpenMultipleTabs]' passed (45.518 seconds).\n[06:57:02:INFO] Test Case '-[NTPHomeTestCase testPositionRestored]' started.\n[06:57:04:INFO] \n[06:57:04:INFO] GMOCK WARNING:\n[06:57:04:INFO] Uninteresting mock function call - returning directly.\n[06:57:04:INFO] Function call: ClearHistory(1601-01-01 00:00:00.000 UTC, 293878-01-10 04:00:54.775 UTC, @0x7ffee114f0f8 8-byte object <B0-ED A5-01 00-60 00-00>)\n[06:57:04:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:05:INFO] [0320/185705.000611:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:57:10:INFO] \n[06:57:10:INFO] GMOCK WARNING:\n[06:57:10:INFO] Uninteresting mock function call - returning directly.\n[06:57:10:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114e8b8 10001|chromium1, @0x7ffee114e618 8-byte object <D0-D3 C5-00 00-60 00-00>)\n[06:57:10:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:10:INFO] \n[06:57:10:INFO] GMOCK WARNING:\n[06:57:10:INFO] Uninteresting mock function call - returning directly.\n[06:57:10:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114e8b8 10001|chromium2, @0x7ffee114e618 8-byte object <B0-7B E5-01 00-60 00-00>)\n[06:57:10:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:10:INFO] \n[06:57:10:INFO] GMOCK WARNING:\n[06:57:10:INFO] Uninteresting mock function call - returning directly.\n[06:57:10:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114e8b8 10001|chromium3, @0x7ffee114e618 8-byte object <00-20 45-02 40-60 00-00>)\n[06:57:10:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:11:INFO] \n[06:57:11:INFO] GMOCK WARNING:\n[06:57:11:INFO] Uninteresting mock function call - returning directly.\n[06:57:11:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114ac18 10001|chromium4, @0x7ffee114a978 8-byte object <90-8F 25-01 00-60 00-00>)\n[06:57:11:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:14:INFO] [0320/185714.343536:WARNING:crw_web_controller.mm(2758)] Unexpected message received: console.msg\n[06:57:14:INFO] [0320/185714.343703:WARNING:crw_web_controller.mm(2810)] Message from JS not handled due to invalid format\n[06:57:14:INFO] \n[06:57:14:INFO] GMOCK WARNING:\n[06:57:14:INFO] Uninteresting mock function call - returning directly.\n[06:57:14:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium1, @0x7ffee114b218 8-byte object <20-09 E5-01 40-60 00-00>)\n[06:57:14:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:14:INFO] \n[06:57:14:INFO] GMOCK WARNING:\n[06:57:14:INFO] Uninteresting mock function call - returning directly.\n[06:57:14:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium2, @0x7ffee114b218 8-byte object <00-48 44-02 40-60 00-00>)\n[06:57:14:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:14:INFO] \n[06:57:14:INFO] GMOCK WARNING:\n[06:57:14:INFO] Uninteresting mock function call - returning directly.\n[06:57:14:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium3, @0x7ffee114b218 8-byte object <10-4F 85-01 00-60 00-00>)\n[06:57:14:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:14:INFO] \n[06:57:14:INFO] GMOCK WARNING:\n[06:57:14:INFO] Uninteresting mock function call - returning directly.\n[06:57:14:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium4, @0x7ffee114b218 8-byte object <E0-3D 84-01 00-60 00-00>)\n[06:57:14:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:17:INFO] Test Case '-[NTPHomeTestCase testPositionRestored]' passed (15.356 seconds).\n[06:57:17:INFO] Test Case '-[NTPHomeTestCase testPositionRestoredWithOmniboxFocused]' started.\n[06:57:19:INFO] \n[06:57:19:INFO] GMOCK WARNING:\n[06:57:19:INFO] Uninteresting mock function call - returning directly.\n[06:57:19:INFO] Function call: ClearHistory(1601-01-01 00:00:00.000 UTC, 293878-01-10 04:00:54.775 UTC, @0x7ffee114f0f8 8-byte object <30-A8 64-01 00-60 00-00>)\n[06:57:19:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:20:INFO] [0320/185720.286384:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:57:25:INFO] \n[06:57:25:INFO] GMOCK WARNING:\n[06:57:25:INFO] Uninteresting mock function call - returning directly.\n[06:57:25:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114e8b8 10001|chromium1, @0x7ffee114e618 8-byte object <30-36 84-00 00-60 00-00>)\n[06:57:25:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:25:INFO] \n[06:57:25:INFO] GMOCK WARNING:\n[06:57:25:INFO] Uninteresting mock function call - returning directly.\n[06:57:25:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114e8b8 10001|chromium2, @0x7ffee114e618 8-byte object <00-90 84-01 40-60 00-00>)\n[06:57:25:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:25:INFO] \n[06:57:25:INFO] GMOCK WARNING:\n[06:57:25:INFO] Uninteresting mock function call - returning directly.\n[06:57:25:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114e8b8 10001|chromium3, @0x7ffee114e618 8-byte object <20-88 04-02 40-60 00-00>)\n[06:57:25:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:27:INFO] \n[06:57:27:INFO] GMOCK WARNING:\n[06:57:27:INFO] Uninteresting mock function call - returning directly.\n[06:57:27:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114ac18 10001|chromium4, @0x7ffee114a978 8-byte object <90-90 64-01 40-60 00-00>)\n[06:57:27:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:28:INFO] \n[06:57:28:INFO] GMOCK WARNING:\n[06:57:28:INFO] Uninteresting mock function call - returning directly.\n[06:57:28:INFO] Function call: FetchSuggestionImageMock(@0x7ffee1149808 10001|chromium5, @0x7ffee1149568 8-byte object <80-46 84-00 00-60 00-00>)\n[06:57:28:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:29:INFO] [0320/185729.823379:WARNING:crw_web_controller.mm(2758)] Unexpected message received: console.msg\n[06:57:29:INFO] [0320/185729.823656:WARNING:crw_web_controller.mm(2810)] Message from JS not handled due to invalid format\n[06:57:31:INFO] \n[06:57:31:INFO] GMOCK WARNING:\n[06:57:31:INFO] Uninteresting mock function call - returning directly.\n[06:57:31:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium1, @0x7ffee114b218 8-byte object <00-47 05-02 00-60 00-00>)\n[06:57:31:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:31:INFO] \n[06:57:31:INFO] GMOCK WARNING:\n[06:57:31:INFO] Uninteresting mock function call - returning directly.\n[06:57:31:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium2, @0x7ffee114b218 8-byte object <80-03 C5-01 40-60 00-00>)\n[06:57:31:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:31:INFO] \n[06:57:31:INFO] GMOCK WARNING:\n[06:57:31:INFO] Uninteresting mock function call - returning directly.\n[06:57:31:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium3, @0x7ffee114b218 8-byte object <90-E6 25-02 00-60 00-00>)\n[06:57:31:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:31:INFO] \n[06:57:31:INFO] GMOCK WARNING:\n[06:57:31:INFO] Uninteresting mock function call - returning directly.\n[06:57:31:INFO] Function call: FetchSuggestionImageMock(@0x7ffee114b4b8 10001|chromium4, @0x7ffee114b218 8-byte object <50-07 84-01 40-60 00-00>)\n[06:57:31:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details.\n[06:57:35:INFO] Test Case '-[NTPHomeTestCase testPositionRestoredWithOmniboxFocused]' passed (17.289 seconds).\n[06:57:35:INFO] Test Case '-[NTPHomeTestCase testPromoTap]' started.\n[06:57:44:INFO] Test Case '-[NTPHomeTestCase testPromoTap]' passed (8.874 seconds).\n[06:57:44:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmnibox]' started.\n[06:57:47:INFO] 2019-03-20 18:57:47.582 ios_chrome_ui_egtests[13658:473122] Attempting to type key h.\n[06:57:47:INFO] 2019-03-20 18:57:47.583 ios_chrome_ui_egtests[13658:473122] Tapping on key: h.\n[06:57:47:INFO] 2019-03-20 18:57:47.668 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:57:47:INFO] 2019-03-20 18:57:47.668 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:57:47:INFO] 2019-03-20 18:57:47.739 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:57:47:INFO] 2019-03-20 18:57:47.739 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:57:47:INFO] 2019-03-20 18:57:47.820 ios_chrome_ui_egtests[13658:473122] Attempting to type key p.\n[06:57:47:INFO] 2019-03-20 18:57:47.821 ios_chrome_ui_egtests[13658:473122] Tapping on key: p.\n[06:57:47:INFO] [0320/185747.879983:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 5\n[06:57:47:INFO] 2019-03-20 18:57:47.895 ios_chrome_ui_egtests[13658:473122] Attempting to type key :.\n[06:57:48:INFO] 2019-03-20 18:57:48.020 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, numbers.\n[06:57:48:INFO] 2019-03-20 18:57:48.073 ios_chrome_ui_egtests[13658:473122] Tapping on key: :.\n[06:57:48:INFO] 2019-03-20 18:57:48.167 ios_chrome_ui_egtests[13658:473122] Attempting to type key /.\n[06:57:48:INFO] 2019-03-20 18:57:48.168 ios_chrome_ui_egtests[13658:473122] Tapping on key: /.\n[06:57:48:INFO] 2019-03-20 18:57:48.251 ios_chrome_ui_egtests[13658:473122] Attempting to type key /.\n[06:57:48:INFO] 2019-03-20 18:57:48.252 ios_chrome_ui_egtests[13658:473122] Tapping on key: /.\n[06:57:48:INFO] [0320/185748.297179:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 11\n[06:57:48:INFO] 2019-03-20 18:57:48.340 ios_chrome_ui_egtests[13658:473122] Attempting to type key 1.\n[06:57:48:INFO] 2019-03-20 18:57:48.340 ios_chrome_ui_egtests[13658:473122] Tapping on key: 1.\n[06:57:48:INFO] [0320/185748.394848:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 13\n[06:57:48:INFO] 2019-03-20 18:57:48.422 ios_chrome_ui_egtests[13658:473122] Attempting to type key 2.\n[06:57:48:INFO] 2019-03-20 18:57:48.423 ios_chrome_ui_egtests[13658:473122] Tapping on key: 2.\n[06:57:48:INFO] 2019-03-20 18:57:48.508 ios_chrome_ui_egtests[13658:473122] Attempting to type key 7.\n[06:57:48:INFO] 2019-03-20 18:57:48.508 ios_chrome_ui_egtests[13658:473122] Tapping on key: 7.\n[06:57:48:INFO] 2019-03-20 18:57:48.603 ios_chrome_ui_egtests[13658:473122] Attempting to type key ..\n[06:57:48:INFO] 2019-03-20 18:57:48.609 ios_chrome_ui_egtests[13658:473122] Tapping on key: ..\n[06:57:48:INFO] 2019-03-20 18:57:48.708 ios_chrome_ui_egtests[13658:473122] Attempting to type key 0.\n[06:57:48:INFO] 2019-03-20 18:57:48.710 ios_chrome_ui_egtests[13658:473122] Tapping on key: 0.\n[06:57:48:INFO] 2019-03-20 18:57:48.806 ios_chrome_ui_egtests[13658:473122] Attempting to type key ..\n[06:57:48:INFO] 2019-03-20 18:57:48.811 ios_chrome_ui_egtests[13658:473122] Tapping on key: ..\n[06:57:48:INFO] 2019-03-20 18:57:48.888 ios_chrome_ui_egtests[13658:473122] Attempting to type key 0.\n[06:57:48:INFO] 2019-03-20 18:57:48.889 ios_chrome_ui_egtests[13658:473122] Tapping on key: 0.\n[06:57:48:INFO] 2019-03-20 18:57:48.965 ios_chrome_ui_egtests[13658:473122] Attempting to type key ..\n[06:57:48:INFO] 2019-03-20 18:57:48.967 ios_chrome_ui_egtests[13658:473122] Tapping on key: ..\n[06:57:49:INFO] 2019-03-20 18:57:49.043 ios_chrome_ui_egtests[13658:473122] Attempting to type key 1.\n[06:57:49:INFO] 2019-03-20 18:57:49.044 ios_chrome_ui_egtests[13658:473122] Tapping on key: 1.\n[06:57:49:INFO] 2019-03-20 18:57:49.128 ios_chrome_ui_egtests[13658:473122] Attempting to type key :.\n[06:57:49:INFO] 2019-03-20 18:57:49.129 ios_chrome_ui_egtests[13658:473122] Tapping on key: :.\n[06:57:49:INFO] [0320/185749.174994:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 27\n[06:57:49:INFO] 2019-03-20 18:57:49.203 ios_chrome_ui_egtests[13658:473122] Attempting to type key 5.\n[06:57:49:INFO] 2019-03-20 18:57:49.204 ios_chrome_ui_egtests[13658:473122] Tapping on key: 5.\n[06:57:49:INFO] [0320/185749.260410:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 29\n[06:57:49:INFO] 2019-03-20 18:57:49.275 ios_chrome_ui_egtests[13658:473122] Attempting to type key 5.\n[06:57:49:INFO] 2019-03-20 18:57:49.276 ios_chrome_ui_egtests[13658:473122] Tapping on key: 5.\n[06:57:49:INFO] 2019-03-20 18:57:49.343 ios_chrome_ui_egtests[13658:473122] Attempting to type key 4.\n[06:57:49:INFO] 2019-03-20 18:57:49.343 ios_chrome_ui_egtests[13658:473122] Tapping on key: 4.\n[06:57:49:INFO] 2019-03-20 18:57:49.412 ios_chrome_ui_egtests[13658:473122] Attempting to type key 9.\n[06:57:49:INFO] 2019-03-20 18:57:49.414 ios_chrome_ui_egtests[13658:473122] Tapping on key: 9.\n[06:57:49:INFO] 2019-03-20 18:57:49.482 ios_chrome_ui_egtests[13658:473122] Attempting to type key 7.\n[06:57:49:INFO] 2019-03-20 18:57:49.483 ios_chrome_ui_egtests[13658:473122] Tapping on key: 7.\n[06:57:49:INFO] 2019-03-20 18:57:49.542 ios_chrome_ui_egtests[13658:473122] Attempting to type key /.\n[06:57:49:INFO] 2019-03-20 18:57:49.543 ios_chrome_ui_egtests[13658:473122] Tapping on key: /.\n[06:57:49:INFO] 2019-03-20 18:57:49.599 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:57:49:INFO] 2019-03-20 18:57:49.907 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, letters.\n[06:57:49:INFO] 2019-03-20 18:57:49.960 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:57:50:INFO] 2019-03-20 18:57:50.012 ios_chrome_ui_egtests[13658:473122] Attempting to type key e.\n[06:57:50:INFO] 2019-03-20 18:57:50.013 ios_chrome_ui_egtests[13658:473122] Tapping on key: e.\n[06:57:50:INFO] 2019-03-20 18:57:50.064 ios_chrome_ui_egtests[13658:473122] Attempting to type key s.\n[06:57:50:INFO] 2019-03-20 18:57:50.065 ios_chrome_ui_egtests[13658:473122] Tapping on key: s.\n[06:57:50:INFO] 2019-03-20 18:57:50.120 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:57:50:INFO] 2019-03-20 18:57:50.120 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:57:50:INFO] 2019-03-20 18:57:50.173 ios_chrome_ui_egtests[13658:473122] Attempting to type key -.\n[06:57:50:INFO] 2019-03-20 18:57:50.276 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, numbers.\n[06:57:50:INFO] 2019-03-20 18:57:50.330 ios_chrome_ui_egtests[13658:473122] Tapping on key: -.\n[06:57:50:INFO] 2019-03-20 18:57:50.384 ios_chrome_ui_egtests[13658:473122] Attempting to type key p.\n[06:57:50:INFO] 2019-03-20 18:57:50.689 ios_chrome_ui_egtests[13658:473122] Tapping on key: more, letters.\n[06:57:50:INFO] 2019-03-20 18:57:50.743 ios_chrome_ui_egtests[13658:473122] Tapping on key: p.\n[06:57:50:INFO] 2019-03-20 18:57:50.795 ios_chrome_ui_egtests[13658:473122] Attempting to type key a.\n[06:57:50:INFO] 2019-03-20 18:57:50.795 ios_chrome_ui_egtests[13658:473122] Tapping on key: a.\n[06:57:50:INFO] 2019-03-20 18:57:50.847 ios_chrome_ui_egtests[13658:473122] Attempting to type key g.\n[06:57:50:INFO] 2019-03-20 18:57:50.849 ios_chrome_ui_egtests[13658:473122] Tapping on key: g.\n[06:57:50:INFO] 2019-03-20 18:57:50.902 ios_chrome_ui_egtests[13658:473122] Attempting to type key e.\n[06:57:50:INFO] 2019-03-20 18:57:50.902 ios_chrome_ui_egtests[13658:473122] Tapping on key: e.\n[06:57:50:INFO] 2019-03-20 18:57:50.955 ios_chrome_ui_egtests[13658:473122] Attempting to type key ..\n[06:57:50:INFO] 2019-03-20 18:57:50.957 ios_chrome_ui_egtests[13658:473122] Tapping on key: ..\n[06:57:51:INFO] 2019-03-20 18:57:51.016 ios_chrome_ui_egtests[13658:473122] Attempting to type key h.\n[06:57:51:INFO] 2019-03-20 18:57:51.017 ios_chrome_ui_egtests[13658:473122] Tapping on key: h.\n[06:57:51:INFO] 2019-03-20 18:57:51.074 ios_chrome_ui_egtests[13658:473122] Attempting to type key t.\n[06:57:51:INFO] 2019-03-20 18:57:51.074 ios_chrome_ui_egtests[13658:473122] Tapping on key: t.\n[06:57:51:INFO] 2019-03-20 18:57:51.131 ios_chrome_ui_egtests[13658:473122] Attempting to type key m.\n[06:57:51:INFO] 2019-03-20 18:57:51.132 ios_chrome_ui_egtests[13658:473122] Tapping on key: m.\n[06:57:51:INFO] 2019-03-20 18:57:51.189 ios_chrome_ui_egtests[13658:473122] Attempting to type key l.\n[06:57:51:INFO] 2019-03-20 18:57:51.190 ios_chrome_ui_egtests[13658:473122] Tapping on key: l.\n[06:57:51:INFO] 2019-03-20 18:57:51.248 ios_chrome_ui_egtests[13658:473122] Attempting to type key\n[06:57:51:INFO] .\n[06:57:51:INFO] 2019-03-20 18:57:51.249 ios_chrome_ui_egtests[13658:473122] Tapping on key: Go.\n[06:57:51:INFO] [0320/185751.656360:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[06:57:54:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmnibox]' passed (10.400 seconds).\n[06:57:54:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScroll]' started.\n[06:58:01:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScroll]' passed (7.493 seconds).\n[06:58:01:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScrollScrolled]' started.\n[06:58:10:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScrollScrolled]' passed (8.510 seconds).\n[06:58:10:INFO] Test Case '-[NTPHomeTestCase testTapOmniboxSearchButtonLogsCorrectly]' started.\n[06:58:14:INFO] Test Case '-[NTPHomeTestCase testTapOmniboxSearchButtonLogsCorrectly]' passed (4.468 seconds).\n[06:58:14:INFO] Test Case '-[NTPHomeTestCase testTapSearchButtonFakeOmniboxScrolled]' started.\n[06:58:22:INFO] Test Case '-[NTPHomeTestCase testTapSearchButtonFakeOmniboxScrolled]' passed (7.712 seconds).\n[06:58:22:INFO] Test Suite 'NTPHomeTestCase' passed at 2019-03-20 18:58:22.665.\n[06:58:22:INFO] \t Executed 16 tests, with 0 failures (0 unexpected) in 213.618 (213.654) seconds\n[06:58:22:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' passed at 2019-03-20 18:58:22.666.\n[06:58:22:INFO] \t Executed 71 tests, with 0 failures (0 unexpected) in 785.283 (787.260) seconds\n[06:58:22:INFO] Test Suite 'Selected tests' passed at 2019-03-20 18:58:22.668.\n[06:58:22:INFO] \t Executed 71 tests, with 0 failures (0 unexpected) in 785.283 (787.262) seconds\n[06:58:23:INFO] \n[06:58:23:INFO] \n[06:58:23:INFO] Test session log:\n[06:58:23:INFO] \t/b/s/w/io1rdEb4/11.4 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-46DB2ABD-EBFE-4A02-8FE9-1AAF8D6ECBA5/ios_chrome_ui_egtests_module-C48A160A-7127-42D2-AABF-90E2E850F5DE/Session-ios_chrome_ui_egtests_module-2019-03-20_184439-BO59OZ.log\n[06:58:23:INFO] \n[06:58:23:INFO] 2019-03-20 18:58:23.119 xcodebuild[13535:471481] [MT] IDETestOperationsObserverDebug: 823.966 elapsed -- Testing started completed.\n[06:58:23:INFO] 2019-03-20 18:58:23.119 xcodebuild[13535:471481] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[06:58:23:INFO] 2019-03-20 18:58:23.119 xcodebuild[13535:471481] [MT] IDETestOperationsObserverDebug: 823.966 sec, +823.966 sec -- end\n[06:58:23:INFO] ** TEST EXECUTE SUCCEEDED **\n[06:58:23:INFO] \n[06:58:27:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/io1rdEb4/run_1553132678.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPhone X', '-resultBundlePath', '/b/s/w/io1rdEb4/11.4 iPhone X/attempt_0'] finished with 0\n[06:58:27:DEBUG] Test ended.\n[06:58:27:DEBUG] Extracting test data.\n[06:58:27:DEBUG] Retrieving crash reports.\n[06:58:27:DEBUG] Retrieving derived data.\n[06:58:27:DEBUG] Making desktop screenshots.\n[06:58:27:DEBUG] Killing simulators.\n[06:58:32:DEBUG] Wiping simulator.\nDevice wiped.\n[06:58:35:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "44096251cbe908c9dd9c609b7b4850fe7aa19498", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43b64a16e0a0b011", "server_versions": [ "4211-90dafba" ], "started_ts": "2019-03-21T01:44:16.041432", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-sdk-simulator", "buildnumber:6708", "data:9e92b3af88de77db57f7305a9842a0f1ed3efdd5", "device_type:iPhone X", "ios_version:11.4", "master:chromium.fyi", "name:ios_chrome_ui_egtests", "os:Mac-10.13.6", "platform:simulator", "pool:Chrome", "priority:35", "service_account:ios-isolated-tester@chops-service-accounts.iam.gserviceaccount.com", "slavename:build111-m9", "spec_name:chromium.ci:ios12-sdk-simulator", "stepname:ios_chrome_ui_egtests (iPhone X iOS 11.4) shard 1 on Mac-10.13.6", "swarming.pool.template:none", "swarming.pool.version:b63ec0b45a6b93fc5873cabac4c1cc31cf2c62c2", "test:ios_chrome_ui_egtests", "user:None" ], "task_id": "43b64a16e0a0b010", "try_number": "1" } ] }