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": [ "build792-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": [ "4201-b04f7f8" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-4" ] } ], "bot_id": "build792-m4", "bot_version": "51d929b905e4fffb3652f17e0ff8dceb0f7077275d215d276fb6c5a2f238b3f7", "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-18T04:22:01.865554", "costs_usd": [ 0.11531924004744519 ], "created_ts": "2019-03-18T04:06:31.738658", "duration": 897.2377710342407, "modified_ts": "2019-03-18T04:22:01.865554", "name": "ios_chrome_integration_egtests (iPhone X iOS 12.1)/Mac-10.13.6/7a0c6e4734/ios12-beta-simulator/3845", "output": "[I 2019-03-17 21:06:46] About to install Xcode 10o45e in Xcode.app for ios\n[09:06:47:INFO] Using Xcode version 10.1 build 10O45e at /b/s/w/ir/Xcode.app/Contents/Developer\nDevice wiped.\n[09:06:56:DEBUG] Setting sigterm handler.\n[09:06:57:INFO] User defaults from command line:\n[09:06:57:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/C211EB27-8B34-428B-B193-1169BA55485C\n[09:06:57:INFO] \n[09:06:58:INFO] Testing started on 'iPhone X'\n[09:06:58:INFO] 2019-03-17 21:06:58.131 xcodebuild[32641:548448] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[09:06:58:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-evzonhxlgrhcgjemgtpjsqbjoobr/Logs/Test/Test-Transient Testing-2019.03.17_21-06-58--0700.xcresult/1_Test/Diagnostics/ios_chrome_integration_egtests_module-63E51B00-8F0B-4690-94BC-58F66D7D3C4C/ios_chrome_integration_egtests_module-AA5CCC69-655C-4BC0-B55E-DB8E5CB8F02C/Session-ios_chrome_integration_egtests_module-2019-03-17_210658-lVYiXK.log\n[09:06:58:INFO] 2019-03-17 21:06:58.132 xcodebuild[32641:548423] [MT] IDETestOperationsObserverDebug: (86D0FEDC-6632-4EDE-AA17-29FDC0487775) Beginning test session ios_chrome_integration_egtests_module-86D0FEDC-6632-4EDE-AA17-29FDC0487775 at 2019-03-17 21:06:58.132 with Xcode 10O45e on target <DVTiPhoneSimulator: 0x7fe259fb7b30> {\n[09:06:58:INFO] \t\tSimDevice: iPhone X (AAB47E0C-2A9A-438D-8A74-2F9AB33B46E5, iOS 12.1, Shutdown)\n[09:06:58:INFO] } (12.1 (16B5084a))\n[09:07:17:INFO] 2019-03-17 21:07:17.728 ios_chrome_integration_egtests[32760:549557] Crash handler setup started.\n[09:07:17:INFO] 2019-03-17 21:07:17.728 ios_chrome_integration_egtests[32760:549557] Crash handler setup completed.\n[09:07:17:INFO] 2019-03-17 21:07:17.729 ios_chrome_integration_egtests[32760:549557] Enabling accessibility for automation on Simulator.\n[09:07:20:INFO] 2019-03-17 21:07:20.483 ios_chrome_integration_egtests[32760:549557] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fcee18a3a00>.\n[09:07:28:INFO] Test Suite 'All tests' started at 2019-03-17 21:07:28.037\n[09:07:28:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-17 21:07:28.037\n[09:07:28:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-17 21:07:28.038\n[09:07:28:INFO] [0317/210728.046469:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:07:28:INFO] [0317/210728.226610:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/AAB47E0C-2A9A-438D-8A74-2F9AB33B46E5/data/Containers/Data/Application/9B8184E4-3041-412C-AB01-7A6B109245E9/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/AAB47E0C-2A9A-438D-8A74-2F9AB33B46E5/data/Containers/Data/Application/9B8184E4-3041-412C-AB01-7A6B109245E9/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[09:07:29:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[09:07:34:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (4.296 seconds).\n[09:07:34:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-17 21:07:34.213.\n[09:07:34:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 4.296 (6.175) seconds\n[09:07:34:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-17 21:07:34.214\n[09:07:34:INFO] [0317/210734.215318:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:07:34:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[09:07:36:INFO] [0317/210736.554851:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:07:43:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (9.079 seconds).\n[09:07:43:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[09:07:45:INFO] [0317/210745.336775:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:07:52:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (9.090 seconds).\n[09:07:52:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[09:07:54:INFO] [0317/210754.728366:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:02:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.345 seconds).\n[09:08:02:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[09:08:04:INFO] [0317/210804.860455:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:11:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.550 seconds).\n[09:08:11:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[09:08:13:INFO] [0317/210813.189964:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:20:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (9.655 seconds).\n[09:08:20:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[09:08:22:INFO] [0317/210822.904841:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:27:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (6.198 seconds).\n[09:08:27:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[09:08:29:INFO] [0317/210829.066553:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:38:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (11.493 seconds).\n[09:08:38:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[09:08:40:INFO] [0317/210840.557194:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:47:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.469 seconds).\n[09:08:47:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[09:08:49:INFO] [0317/210849.054205:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:56:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (9.821 seconds).\n[09:08:56:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-17 21:08:56.929.\n[09:08:56:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 82.700 (82.715) seconds\n[09:08:56:INFO] Test Suite 'CookiesTestCase' started at 2019-03-17 21:08:56.930\n[09:08:56:INFO] [0317/210856.931448:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:08:56:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[09:08:58:INFO] [0317/210858.914491:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:09:14:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.602 seconds).\n[09:09:14:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[09:09:29:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.779 seconds).\n[09:09:29:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[09:09:38:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.691 seconds).\n[09:09:38:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[09:09:51:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.524 seconds).\n[09:09:51:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[09:10:06:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (14.620 seconds).\n[09:10:06:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-17 21:10:06.154.\n[09:10:06:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 69.216 (69.224) seconds\n[09:10:06:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-17 21:10:06.155\n[09:10:06:INFO] [0317/211006.155899:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:06:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[09:10:07:INFO] 2019-03-17 21:10:07.724 ios_chrome_integration_egtests[32760:549557] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[09:10:09:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.455 seconds).\n[09:10:09:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[09:10:11:INFO] 2019-03-17 21:10:11.443 ios_chrome_integration_egtests[32760:549557] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[09:10:13:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.708 seconds).\n[09:10:13:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-17 21:10:13.324.\n[09:10:13:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 7.162 (7.169) seconds\n[09:10:13:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-17 21:10:13.325\n[09:10:13:INFO] [0317/211013.325948:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:13:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[09:10:23:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.965 seconds).\n[09:10:23:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[09:10:33:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (10.178 seconds).\n[09:10:33:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[09:10:39:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.990 seconds).\n[09:10:39:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[09:10:43:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.719 seconds).\n[09:10:43:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[09:10:53:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.873 seconds).\n[09:10:53:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-17 21:10:53.058.\n[09:10:53:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 39.725 (39.734) seconds\n[09:10:53:INFO] Test Suite 'FormInputTestCase' started at 2019-03-17 21:10:53.059\n[09:10:53:INFO] [0317/211053.060425:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:53:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[09:10:56:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.747 seconds).\n[09:10:56:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[09:10:58:INFO] [0317/211058.751793:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:11:00:INFO] 2019-03-17 21:11:00.498 ios_chrome_integration_egtests[32760:549557] API error: <_UIKBCompatInputView: 0x7fcee482cab0; frame = (0 0; 0 0); layer = <CALayer: 0x600002db13c0>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[09:11:00:INFO] 2019-03-17 21:11:00.499 ios_chrome_integration_egtests[32760:549557] API error: <_UIKBCompatInputView: 0x7fcee482cab0; frame = (0 0; 0 0); layer = <CALayer: 0x600002db13c0>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[09:11:05:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (8.974 seconds).\n[09:11:05:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-17 21:11:05.786.\n[09:11:05:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 12.721 (12.727) seconds\n[09:11:05:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-17 21:11:05.787\n[09:11:05:INFO] [0317/211105.788115:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:11:05:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[09:11:07:INFO] [0317/211107.788150:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:11:17:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (11.359 seconds).\n[09:11:17:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[09:11:20:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.741 seconds).\n[09:11:20:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[09:11:31:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.751 seconds).\n[09:11:31:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[09:11:36:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.864 seconds).\n[09:11:36:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[09:11:44:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.867 seconds).\n[09:11:44:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[09:11:50:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.206 seconds).\n[09:11:50:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-17 21:11:50.583.\n[09:11:50:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 44.789 (44.796) seconds\n[09:11:50:INFO] Test Suite 'NTPTilesTest' started at 2019-03-17 21:11:50.584\n[09:11:50:INFO] [0317/211150.584941:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:11:50:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[09:11:54:INFO] [0317/211154.602566:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:12:00:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (10.063 seconds).\n[09:12:00:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[09:12:25:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (24.747 seconds).\n[09:12:25:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-17 21:12:25.403.\n[09:12:25:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 34.810 (34.819) seconds\n[09:12:25:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-17 21:12:25.403\n[09:12:25:INFO] [0317/211225.404896:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:12:25:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[09:12:27:INFO] [0317/211227.480402:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:12:34:INFO] 2019-03-17 21:12:34.368 ios_chrome_integration_egtests[32760:549557] Attempting to type key 1.\n[09:12:34:INFO] 2019-03-17 21:12:34.471 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, numbers.\n[09:12:34:INFO] 2019-03-17 21:12:34.556 ios_chrome_integration_egtests[32760:549557] Tapping on key: 1.\n[09:12:34:INFO] 2019-03-17 21:12:34.663 ios_chrome_integration_egtests[32760:549557] Attempting to type key 2.\n[09:12:34:INFO] 2019-03-17 21:12:34.663 ios_chrome_integration_egtests[32760:549557] Tapping on key: 2.\n[09:12:34:INFO] 2019-03-17 21:12:34.739 ios_chrome_integration_egtests[32760:549557] Attempting to type key 7.\n[09:12:34:INFO] 2019-03-17 21:12:34.741 ios_chrome_integration_egtests[32760:549557] Tapping on key: 7.\n[09:12:34:INFO] [0317/211234.786467:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 3\n[09:12:34:INFO] 2019-03-17 21:12:34.814 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:34:INFO] 2019-03-17 21:12:34.816 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:34:INFO] 2019-03-17 21:12:34.887 ios_chrome_integration_egtests[32760:549557] Attempting to type key 0.\n[09:12:34:INFO] 2019-03-17 21:12:34.888 ios_chrome_integration_egtests[32760:549557] Tapping on key: 0.\n[09:12:34:INFO] 2019-03-17 21:12:34.954 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:34:INFO] 2019-03-17 21:12:34.955 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:35:INFO] 2019-03-17 21:12:35.025 ios_chrome_integration_egtests[32760:549557] Attempting to type key 0.\n[09:12:35:INFO] 2019-03-17 21:12:35.026 ios_chrome_integration_egtests[32760:549557] Tapping on key: 0.\n[09:12:35:INFO] 2019-03-17 21:12:35.097 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:35:INFO] 2019-03-17 21:12:35.099 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:35:INFO] 2019-03-17 21:12:35.164 ios_chrome_integration_egtests[32760:549557] Attempting to type key 1.\n[09:12:35:INFO] 2019-03-17 21:12:35.167 ios_chrome_integration_egtests[32760:549557] Tapping on key: 1.\n[09:12:35:INFO] [0317/211235.216867:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 11\n[09:12:35:INFO] 2019-03-17 21:12:35.242 ios_chrome_integration_egtests[32760:549557] Attempting to type key :.\n[09:12:35:INFO] 2019-03-17 21:12:35.243 ios_chrome_integration_egtests[32760:549557] Tapping on key: :.\n[09:12:35:INFO] 2019-03-17 21:12:35.317 ios_chrome_integration_egtests[32760:549557] Attempting to type key 6.\n[09:12:35:INFO] 2019-03-17 21:12:35.318 ios_chrome_integration_egtests[32760:549557] Tapping on key: 6.\n[09:12:35:INFO] 2019-03-17 21:12:35.389 ios_chrome_integration_egtests[32760:549557] Attempting to type key 4.\n[09:12:35:INFO] 2019-03-17 21:12:35.390 ios_chrome_integration_egtests[32760:549557] Tapping on key: 4.\n[09:12:35:INFO] 2019-03-17 21:12:35.462 ios_chrome_integration_egtests[32760:549557] Attempting to type key 2.\n[09:12:35:INFO] 2019-03-17 21:12:35.463 ios_chrome_integration_egtests[32760:549557] Tapping on key: 2.\n[09:12:35:INFO] 2019-03-17 21:12:35.534 ios_chrome_integration_egtests[32760:549557] Attempting to type key 3.\n[09:12:35:INFO] 2019-03-17 21:12:35.535 ios_chrome_integration_egtests[32760:549557] Tapping on key: 3.\n[09:12:35:INFO] 2019-03-17 21:12:35.610 ios_chrome_integration_egtests[32760:549557] Attempting to type key 8.\n[09:12:35:INFO] 2019-03-17 21:12:35.611 ios_chrome_integration_egtests[32760:549557] Tapping on key: 8.\n[09:12:35:INFO] 2019-03-17 21:12:35.676 ios_chrome_integration_egtests[32760:549557] Attempting to type key /.\n[09:12:35:INFO] 2019-03-17 21:12:35.677 ios_chrome_integration_egtests[32760:549557] Tapping on key: /.\n[09:12:35:INFO] 2019-03-17 21:12:35.736 ios_chrome_integration_egtests[32760:549557] Attempting to type key t.\n[09:12:36:INFO] 2019-03-17 21:12:36.040 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, letters.\n[09:12:36:INFO] 2019-03-17 21:12:36.093 ios_chrome_integration_egtests[32760:549557] Tapping on key: t.\n[09:12:36:INFO] 2019-03-17 21:12:36.149 ios_chrome_integration_egtests[32760:549557] Attempting to type key e.\n[09:12:36:INFO] 2019-03-17 21:12:36.149 ios_chrome_integration_egtests[32760:549557] Tapping on key: e.\n[09:12:36:INFO] 2019-03-17 21:12:36.212 ios_chrome_integration_egtests[32760:549557] Attempting to type key s.\n[09:12:36:INFO] 2019-03-17 21:12:36.213 ios_chrome_integration_egtests[32760:549557] Tapping on key: s.\n[09:12:36:INFO] 2019-03-17 21:12:36.273 ios_chrome_integration_egtests[32760:549557] Attempting to type key t.\n[09:12:36:INFO] 2019-03-17 21:12:36.274 ios_chrome_integration_egtests[32760:549557] Tapping on key: t.\n[09:12:36:INFO] 2019-03-17 21:12:36.335 ios_chrome_integration_egtests[32760:549557] Attempting to type key -.\n[09:12:36:INFO] 2019-03-17 21:12:36.436 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, numbers.\n[09:12:36:INFO] 2019-03-17 21:12:36.488 ios_chrome_integration_egtests[32760:549557] Tapping on key: -.\n[09:12:36:INFO] 2019-03-17 21:12:36.552 ios_chrome_integration_egtests[32760:549557] Attempting to type key p.\n[09:12:36:INFO] 2019-03-17 21:12:36.860 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, letters.\n[09:12:36:INFO] 2019-03-17 21:12:36.913 ios_chrome_integration_egtests[32760:549557] Tapping on key: p.\n[09:12:36:INFO] 2019-03-17 21:12:36.971 ios_chrome_integration_egtests[32760:549557] Attempting to type key a.\n[09:12:36:INFO] 2019-03-17 21:12:36.972 ios_chrome_integration_egtests[32760:549557] Tapping on key: a.\n[09:12:37:INFO] 2019-03-17 21:12:37.034 ios_chrome_integration_egtests[32760:549557] Attempting to type key g.\n[09:12:37:INFO] 2019-03-17 21:12:37.035 ios_chrome_integration_egtests[32760:549557] Tapping on key: g.\n[09:12:37:INFO] 2019-03-17 21:12:37.097 ios_chrome_integration_egtests[32760:549557] Attempting to type key e.\n[09:12:37:INFO] 2019-03-17 21:12:37.097 ios_chrome_integration_egtests[32760:549557] Tapping on key: e.\n[09:12:37:INFO] 2019-03-17 21:12:37.159 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:37:INFO] 2019-03-17 21:12:37.162 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:37:INFO] 2019-03-17 21:12:37.223 ios_chrome_integration_egtests[32760:549557] Attempting to type key h.\n[09:12:37:INFO] 2019-03-17 21:12:37.224 ios_chrome_integration_egtests[32760:549557] Tapping on key: h.\n[09:12:37:INFO] 2019-03-17 21:12:37.303 ios_chrome_integration_egtests[32760:549557] Attempting to type key t.\n[09:12:37:INFO] 2019-03-17 21:12:37.305 ios_chrome_integration_egtests[32760:549557] Tapping on key: t.\n[09:12:37:INFO] 2019-03-17 21:12:37.367 ios_chrome_integration_egtests[32760:549557] Attempting to type key m.\n[09:12:37:INFO] 2019-03-17 21:12:37.368 ios_chrome_integration_egtests[32760:549557] Tapping on key: m.\n[09:12:37:INFO] 2019-03-17 21:12:37.430 ios_chrome_integration_egtests[32760:549557] Attempting to type key l.\n[09:12:37:INFO] 2019-03-17 21:12:37.431 ios_chrome_integration_egtests[32760:549557] Tapping on key: l.\n[09:12:37:INFO] 2019-03-17 21:12:37.491 ios_chrome_integration_egtests[32760:549557] Attempting to type key\n[09:12:37:INFO] .\n[09:12:37:INFO] 2019-03-17 21:12:37.492 ios_chrome_integration_egtests[32760:549557] Tapping on key: Go.\n[09:12:43:INFO] 2019-03-17 21:12:43.813 ios_chrome_integration_egtests[32760:549557] Attempting to type key 1.\n[09:12:43:INFO] 2019-03-17 21:12:43.916 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, numbers.\n[09:12:43:INFO] 2019-03-17 21:12:43.969 ios_chrome_integration_egtests[32760:549557] Tapping on key: 1.\n[09:12:44:INFO] 2019-03-17 21:12:44.049 ios_chrome_integration_egtests[32760:549557] Attempting to type key 2.\n[09:12:44:INFO] 2019-03-17 21:12:44.050 ios_chrome_integration_egtests[32760:549557] Tapping on key: 2.\n[09:12:44:INFO] 2019-03-17 21:12:44.130 ios_chrome_integration_egtests[32760:549557] Attempting to type key 7.\n[09:12:44:INFO] 2019-03-17 21:12:44.131 ios_chrome_integration_egtests[32760:549557] Tapping on key: 7.\n[09:12:44:INFO] 2019-03-17 21:12:44.215 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:44:INFO] 2019-03-17 21:12:44.217 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:44:INFO] [0317/211244.270485:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 19\n[09:12:44:INFO] 2019-03-17 21:12:44.303 ios_chrome_integration_egtests[32760:549557] Attempting to type key 0.\n[09:12:44:INFO] 2019-03-17 21:12:44.304 ios_chrome_integration_egtests[32760:549557] Tapping on key: 0.\n[09:12:44:INFO] 2019-03-17 21:12:44.380 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:44:INFO] 2019-03-17 21:12:44.381 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:44:INFO] 2019-03-17 21:12:44.476 ios_chrome_integration_egtests[32760:549557] Attempting to type key 0.\n[09:12:44:INFO] 2019-03-17 21:12:44.481 ios_chrome_integration_egtests[32760:549557] Tapping on key: 0.\n[09:12:44:INFO] 2019-03-17 21:12:44.570 ios_chrome_integration_egtests[32760:549557] Attempting to type key ..\n[09:12:44:INFO] 2019-03-17 21:12:44.573 ios_chrome_integration_egtests[32760:549557] Tapping on key: ..\n[09:12:44:INFO] 2019-03-17 21:12:44.711 ios_chrome_integration_egtests[32760:549557] Attempting to type key 1.\n[09:12:44:INFO] 2019-03-17 21:12:44.716 ios_chrome_integration_egtests[32760:549557] Tapping on key: 1.\n[09:12:44:INFO] 2019-03-17 21:12:44.788 ios_chrome_integration_egtests[32760:549557] Attempting to type key :.\n[09:12:44:INFO] 2019-03-17 21:12:44.790 ios_chrome_integration_egtests[32760:549557] Tapping on key: :.\n[09:12:44:INFO] [0317/211244.842240:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 29\n[09:12:44:INFO] 2019-03-17 21:12:44.856 ios_chrome_integration_egtests[32760:549557] Attempting to type key 6.\n[09:12:44:INFO] 2019-03-17 21:12:44.857 ios_chrome_integration_egtests[32760:549557] Tapping on key: 6.\n[09:12:44:INFO] 2019-03-17 21:12:44.926 ios_chrome_integration_egtests[32760:549557] Attempting to type key 4.\n[09:12:44:INFO] 2019-03-17 21:12:44.927 ios_chrome_integration_egtests[32760:549557] Tapping on key: 4.\n[09:12:44:INFO] 2019-03-17 21:12:44.994 ios_chrome_integration_egtests[32760:549557] Attempting to type key 2.\n[09:12:44:INFO] 2019-03-17 21:12:44.995 ios_chrome_integration_egtests[32760:549557] Tapping on key: 2.\n[09:12:45:INFO] 2019-03-17 21:12:45.080 ios_chrome_integration_egtests[32760:549557] Attempting to type key 3.\n[09:12:45:INFO] 2019-03-17 21:12:45.081 ios_chrome_integration_egtests[32760:549557] Tapping on key: 3.\n[09:12:45:INFO] 2019-03-17 21:12:45.144 ios_chrome_integration_egtests[32760:549557] Attempting to type key 8.\n[09:12:45:INFO] 2019-03-17 21:12:45.145 ios_chrome_integration_egtests[32760:549557] Tapping on key: 8.\n[09:12:45:INFO] 2019-03-17 21:12:45.210 ios_chrome_integration_egtests[32760:549557] Attempting to type key /.\n[09:12:45:INFO] 2019-03-17 21:12:45.211 ios_chrome_integration_egtests[32760:549557] Tapping on key: /.\n[09:12:45:INFO] 2019-03-17 21:12:45.273 ios_chrome_integration_egtests[32760:549557] Attempting to type key t.\n[09:12:45:INFO] 2019-03-17 21:12:45.579 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, letters.\n[09:12:45:INFO] 2019-03-17 21:12:45.632 ios_chrome_integration_egtests[32760:549557] Tapping on key: t.\n[09:12:45:INFO] 2019-03-17 21:12:45.693 ios_chrome_integration_egtests[32760:549557] Attempting to type key e.\n[09:12:45:INFO] 2019-03-17 21:12:45.694 ios_chrome_integration_egtests[32760:549557] Tapping on key: e.\n[09:12:45:INFO] 2019-03-17 21:12:45.758 ios_chrome_integration_egtests[32760:549557] Attempting to type key s.\n[09:12:45:INFO] 2019-03-17 21:12:45.759 ios_chrome_integration_egtests[32760:549557] Tapping on key: s.\n[09:12:45:INFO] 2019-03-17 21:12:45.822 ios_chrome_integration_egtests[32760:549557] Attempting to type key t.\n[09:12:45:INFO] 2019-03-17 21:12:45.823 ios_chrome_integration_egtests[32760:549557] Tapping on key: t.\n[09:12:45:INFO] 2019-03-17 21:12:45.886 ios_chrome_integration_egtests[32760:549557] Attempting to type key -.\n[09:12:45:INFO] 2019-03-17 21:12:45.989 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, numbers.\n[09:12:46:INFO] 2019-03-17 21:12:46.043 ios_chrome_integration_egtests[32760:549557] Tapping on key: -.\n[09:12:46:INFO] 2019-03-17 21:12:46.106 ios_chrome_integration_egtests[32760:549557] Attempting to type key p.\n[09:12:46:INFO] 2019-03-17 21:12:46.413 ios_chrome_integration_egtests[32760:549557] Tapping on key: more, letters.\n[09:12:46:INFO] 2019-03-17 21:12:46.467 ios_chrome_integration_egtests[32760:549557] Tapping on key: p.\n[09:12:46:INFO] 2019-03-17 21:12:46.527 ios_chrome_integration_egtests[32760:549557] Attempting to type key a.\n[09:12:46:INFO] 2019-03-17 21:12:46.528 ios_chrome_integration_egtests[32760:549557] Tapping on key: a.\n[09:12:46:INFO] 2019-03-17 21:12:46.592 ios_chrome_integration_egtests[32760:549557] Attempting to type key g.\n[09:12:46:INFO] 2019-03-17 21:12:46.593 ios_chrome_integration_egtests[32760:549557] Tapping on key: g.\n[09:12:51:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (25.911 seconds).\n[09:12:51:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-17 21:12:51.328.\n[09:12:51:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 25.911 (25.925) seconds\n[09:12:51:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-17 21:12:51.329\n[09:12:51:INFO] [0317/211251.330535:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:12:51:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[09:12:55:INFO] [0317/211255.139005:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:13:07:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (16.422 seconds).\n[09:13:07:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[09:13:36:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (28.913 seconds).\n[09:13:36:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[09:13:54:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.793 seconds).\n[09:13:54:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[09:14:13:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (19.052 seconds).\n[09:14:13:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[09:14:29:INFO] [0317/211429.915287:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:14:29:INFO] [0317/211429.947681:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:14:30:INFO] [0317/211429.952321:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:14:32:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (19.480 seconds).\n[09:14:32:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[09:14:39:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (6.452 seconds).\n[09:14:39:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[09:14:46:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.543 seconds).\n[09:14:46:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[09:14:57:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (10.459 seconds).\n[09:14:57:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[09:15:31:INFO] [0317/211531.818847:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:31:INFO] [0317/211531.848022:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:31:INFO] [0317/211531.853978:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:31:INFO] [0317/211531.858078:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:31:INFO] [0317/211531.865705:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:34:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (37.509 seconds).\n[09:15:34:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[09:15:50:INFO] [0317/211550.484864:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:50:INFO] [0317/211550.495055:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:50:INFO] [0317/211550.502053:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:50:INFO] [0317/211550.506753:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:50:INFO] [0317/211550.520827:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:50:INFO] [0317/211550.522144:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:53:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (18.614 seconds).\n[09:15:53:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[09:16:08:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.274 seconds).\n[09:16:08:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-17 21:16:08.850.\n[09:16:08:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 197.511 (197.521) seconds\n[09:16:08:INFO] Test Suite 'UKMTestCase' started at 2019-03-17 21:16:08.851\n[09:16:08:INFO] [0317/211608.852562:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:16:08:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[09:16:16:INFO] 2019-03-17 21:16:16.317 ios_chrome_integration_egtests[32760:549557] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fcee4b9c4b0; baseClass = UIButton; frame = (16 726; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x60000023ea00>>, Touch Target: {86, 36}\n[09:16:17:INFO] [0317/211617.766111:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:16:17:INFO] [0317/211617.799637:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:16:17:INFO] [0317/211617.815184:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:16:17:INFO] [0317/211617.815318:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:16:18:INFO] [0317/211618.853590:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:16:30:INFO] [0317/211630.609173:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:16:41:INFO] [0317/211641.478608:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:16:41:INFO] [0317/211641.478743:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:16:42:INFO] [0317/211642.571626:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:16:42:INFO] [0317/211642.690474:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[09:16:54:INFO] [0317/211654.357251:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:16:58:INFO] [0317/211658.312868:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:17:00:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (51.355 seconds).\n[09:17:00:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[09:17:09:INFO] [0317/211709.295970:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:09:INFO] [0317/211709.297810:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:09:INFO] [0317/211709.308085:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:17:09:INFO] [0317/211709.308227:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:17:10:INFO] [0317/211710.402441:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:17:27:INFO] [0317/211727.823806:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:17:31:INFO] [0317/211731.784935:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:17:33:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (33.466 seconds).\n[09:17:33:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[09:17:42:INFO] [0317/211742.786332:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:42:INFO] [0317/211742.788494:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:42:INFO] [0317/211742.795891:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:17:42:INFO] [0317/211742.795997:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:17:43:INFO] [0317/211743.889283:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:17:55:INFO] [0317/211755.676172:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:17:55:INFO] [0317/211755.900493:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[09:17:55:INFO] [0317/211755.900654:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[09:17:59:INFO] [0317/211759.638678:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:18:01:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (27.855 seconds).\n[09:18:01:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[09:18:10:INFO] [0317/211810.624416:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:18:10:INFO] [0317/211810.626118:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:18:10:INFO] [0317/211810.636431:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:18:10:INFO] [0317/211810.636549:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:18:11:INFO] [0317/211811.732524:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:18:33:INFO] [0317/211833.644387:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:18:37:INFO] [0317/211837.605940:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:18:39:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (38.016 seconds).\n[09:18:39:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[09:18:48:INFO] [0317/211848.650648:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:18:48:INFO] [0317/211848.653310:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:18:48:INFO] [0317/211848.666603:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:18:48:INFO] [0317/211848.666775:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:18:49:INFO] [0317/211849.753991:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:22:INFO] [0317/211922.511389:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:19:22:INFO] [0317/211922.744292:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[09:19:22:INFO] [0317/211922.744697:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[09:19:26:INFO] [0317/211926.473712:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:19:33:INFO] [0317/211933.382333:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:19:33:INFO] [0317/211933.384307:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:19:33:INFO] [0317/211933.384437:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:19:33:INFO] [0317/211933.385675:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:19:34:INFO] [0317/211934.481325:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:46:INFO] [0317/211946.301023:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:19:46:INFO] [0317/211946.518713:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[09:19:46:INFO] [0317/211946.518914:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[09:19:50:INFO] [0317/211950.263328:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:19:52:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (72.601 seconds).\n[09:19:52:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[09:20:01:INFO] [0317/212001.232243:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:20:01:INFO] [0317/212001.234773:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:20:01:INFO] [0317/212001.242809:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:20:01:INFO] [0317/212001.242919:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:20:02:INFO] [0317/212002.340922:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:16:INFO] [0317/212016.641564:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:18:INFO] [0317/212018.207517:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:19:INFO] [0317/212019.771873:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:21:INFO] [0317/212021.338110:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:31:INFO] [0317/212031.580684:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:20:35:INFO] [0317/212035.543618:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:20:37:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (45.287 seconds).\n[09:20:37:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[09:20:46:INFO] [0317/212046.569270:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:20:46:INFO] [0317/212046.572952:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:20:46:INFO] [0317/212046.573718:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:20:46:INFO] [0317/212046.573802:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:20:47:INFO] [0317/212047.676364:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:59:INFO] [0317/212059.469996:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:21:10:INFO] [0317/212110.329399:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:21:10:INFO] [0317/212110.329562:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:21:11:INFO] [0317/212111.434933:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:21:11:INFO] [0317/212111.545635:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[09:21:23:INFO] [0317/212123.235069:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:21:23:INFO] [0317/212123.460853:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[09:21:23:INFO] [0317/212123.461077:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[09:21:27:INFO] [0317/212127.198552:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:21:29:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (51.654 seconds).\n[09:21:29:INFO] Test Suite 'UKMTestCase' passed at 2019-03-17 21:21:29.094.\n[09:21:29:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 320.235 (320.243) seconds\n[09:21:29:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-17 21:21:29.095.\n[09:21:29:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 839.076 (841.057) seconds\n[09:21:29:INFO] Test Suite 'All tests' passed at 2019-03-17 21:21:29.096.\n[09:21:29:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 839.076 (841.059) seconds\n[09:21:29:INFO] \n[09:21:29:INFO] \n[09:21:29:INFO] Test session results and logs:\n[09:21:29:INFO] \t/Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-evzonhxlgrhcgjemgtpjsqbjoobr/Logs/Test/Test-Transient Testing-2019.03.17_21-06-58--0700.xcresult\n[09:21:29:INFO] \n[09:21:29:INFO] 2019-03-17 21:21:29.575 xcodebuild[32641:548423] [MT] IDETestOperationsObserverDebug: 871.521 elapsed -- Testing started completed.\n[09:21:29:INFO] 2019-03-17 21:21:29.575 xcodebuild[32641:548423] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[09:21:29:INFO] 2019-03-17 21:21:29.575 xcodebuild[32641:548423] [MT] IDETestOperationsObserverDebug: 871.521 sec, +871.521 sec -- end\n[09:21:29:INFO] ** TEST EXECUTE SUCCEEDED **\n[09:21:29:INFO] \n[09:21:35:DEBUG] Finished print_process_output.\n[09:21:35:INFO] Waiting for test process to terminate.\n[09:21:35:INFO] Test process terminated.\n[09:21:35:DEBUG] Setting sigterm handler.\n[09:21:35:DEBUG] Stdout flushed after test process.\n[09:21:35:DEBUG] Processing test results.\n[09:21:35:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0\n\n[09:21:35:DEBUG] Extracting test data.\n[09:21:36:DEBUG] Retrieving crash reports.\n[09:21:36:DEBUG] Retrieving derived data.\n[09:21:36:DEBUG] Making desktop screenshots.\n[09:21:36:DEBUG] Killing simulators.\n[09:21:41:DEBUG] Wiping simulator.\nDevice wiped.\n[09:21:43:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "63ce9b06d5089cc511f527da240e7427b7f8d629", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43a7cc48531ac811", "server_versions": [ "4201-b04f7f8" ], "started_ts": "2019-03-18T04:06:32.240937", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-beta-simulator", "buildnumber:3845", "data:7a0c6e4734f7f54333ef6cba31f902ac01c90619", "device_type:iPhone X", "ios_version:12.1", "master:chromium.fyi", "name:ios_chrome_integration_egtests", "os:Mac-10.13.6", "platform:simulator", "pool:Chrome", "priority:200", "service_account:ios-isolated-tester@chops-service-accounts.iam.gserviceaccount.com", "slavename:build228-m9", "spec_name:chromium.ci:ios12-beta-simulator", "stepname:ios_chrome_integration_egtests (iPhone X iOS 12.1) on Mac-10.13.6", "swarming.pool.template:none", "swarming.pool.version:84dc2e055f380db17943d027893da36f2780b31c", "test:ios_chrome_integration_egtests", "user:None" ], "task_id": "43a7cc48531ac810", "try_number": "1" } ] }