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": [ "build761-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": "build761-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:21:13.020133", "costs_usd": [ 0.1072913185384576 ], "created_ts": "2019-03-18T04:06:47.556181", "duration": 838.4294049739838, "modified_ts": "2019-03-18T04:21:13.020133", "name": "ios_chrome_integration_egtests (iPhone 5s iOS 12.1)/Mac-10.13.6/57d7ba6caf/ios12-beta-simulator/3845", "output": "[I 2019-03-17 21:07:02] About to install Xcode 10o45e in Xcode.app for ios\n[09:07:03:INFO] Using Xcode version 10.1 build 10O45e at /b/s/w/ir/Xcode.app/Contents/Developer\nDevice wiped.\n[09:07:07:DEBUG] Setting sigterm handler.\n[09:07:08:INFO] User defaults from command line:\n[09:07:08:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/75D6836B-706F-4BFB-8FAC-90CA6CEECC3F\n[09:07:08:INFO] \n[09:07:08:INFO] Testing started on 'iPhone 5s'\n[09:07:08:INFO] 2019-03-17 21:07:08.736 xcodebuild[4612:72028] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[09:07:08:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-crgrfchyrprdngbolsmsfgxnwtqy/Logs/Test/Test-Transient Testing-2019.03.17_21-07-08--0700.xcresult/1_Test/Diagnostics/ios_chrome_integration_egtests_module-E4BD5B2E-E836-4835-8D65-83B860FC362D/ios_chrome_integration_egtests_module-03E4EB49-A32E-4786-8E21-BC309B748A93/Session-ios_chrome_integration_egtests_module-2019-03-17_210708-Q7ruJd.log\n[09:07:08:INFO] 2019-03-17 21:07:08.737 xcodebuild[4612:72005] [MT] IDETestOperationsObserverDebug: (8D871196-B0BE-4F13-97C7-2DD89A9A6D93) Beginning test session ios_chrome_integration_egtests_module-8D871196-B0BE-4F13-97C7-2DD89A9A6D93 at 2019-03-17 21:07:08.737 with Xcode 10O45e on target <DVTiPhoneSimulator: 0x7fc2fe7e5030> {\n[09:07:08:INFO] \t\tSimDevice: iPhone 5s (792F5EF0-CF0D-4594-803F-6EBA8FE0AD43, iOS 12.1, Shutdown)\n[09:07:08:INFO] } (12.1 (16B5084a))\n[09:07:25:INFO] 2019-03-17 21:07:25.392 ios_chrome_integration_egtests[4727:73092] Crash handler setup started.\n[09:07:25:INFO] 2019-03-17 21:07:25.392 ios_chrome_integration_egtests[4727:73092] Crash handler setup completed.\n[09:07:25:INFO] 2019-03-17 21:07:25.392 ios_chrome_integration_egtests[4727:73092] Enabling accessibility for automation on Simulator.\n[09:07:28:INFO] 2019-03-17 21:07:28.216 ios_chrome_integration_egtests[4727:73092] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f9bca01b600>.\n[09:07:33:INFO] [0317/210733.230609:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/792F5EF0-CF0D-4594-803F-6EBA8FE0AD43/data/Containers/Data/Application/55B21580-E136-4094-A2C2-EA5AF43A0A3E/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/792F5EF0-CF0D-4594-803F-6EBA8FE0AD43/data/Containers/Data/Application/55B21580-E136-4094-A2C2-EA5AF43A0A3E/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[09:07:36:INFO] Test Suite 'All tests' started at 2019-03-17 21:07:36.698\n[09:07:36:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-17 21:07:36.699\n[09:07:36:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-17 21:07:36.699\n[09:07:36:INFO] [0317/210736.708034:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:07:38:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[09:07:42:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (3.850 seconds).\n[09:07:42:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-17 21:07:42.480.\n[09:07:42:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 3.850 (5.781) seconds\n[09:07:42:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-17 21:07:42.481\n[09:07:42:INFO] [0317/210742.482535:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:07:42:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[09:07:44:INFO] [0317/210744.502477:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:07:49:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (7.423 seconds).\n[09:07:49:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[09:07:51:INFO] [0317/210751.871314:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:07:58:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (8.468 seconds).\n[09:07:58:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[09:08:00:INFO] [0317/210800.552816:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:08:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (9.780 seconds).\n[09:08:08:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[09:08:10:INFO] [0317/210810.100992:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:16:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.110 seconds).\n[09:08:16:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[09:08:18:INFO] [0317/210818.148197:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:25:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (9.209 seconds).\n[09:08:25:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[09:08:27:INFO] [0317/210827.272918:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:31:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (5.779 seconds).\n[09:08:31:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[09:08:33:INFO] [0317/210833.107248:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:41:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (10.291 seconds).\n[09:08:41:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[09:08:43:INFO] [0317/210843.415845:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:49:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (7.822 seconds).\n[09:08:49:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[09:08:51:INFO] [0317/210851.197037:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:08:58:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (9.274 seconds).\n[09:08:58:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-17 21:08:58.650.\n[09:08:58:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 76.156 (76.169) seconds\n[09:08:58:INFO] Test Suite 'CookiesTestCase' started at 2019-03-17 21:08:58.651\n[09:08:58:INFO] [0317/210858.652611:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:08:58:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[09:09:00:INFO] [0317/210900.547775:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:09:15:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (16.853 seconds).\n[09:09:15:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[09:09:29:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.071 seconds).\n[09:09:29:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[09:09:37:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.395 seconds).\n[09:09:37:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[09:09:50:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (12.914 seconds).\n[09:09:50:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[09:10:04:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (13.900 seconds).\n[09:10:04:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-17 21:10:04.792.\n[09:10:04:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 66.134 (66.141) seconds\n[09:10:04:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-17 21:10:04.793\n[09:10:04:INFO] [0317/211004.794076:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:04:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[09:10:06:INFO] 2019-03-17 21:10:06.333 ios_chrome_integration_egtests[4727:73092] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[09:10:08:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.398 seconds).\n[09:10:08:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[09:10:09:INFO] 2019-03-17 21:10:09.937 ios_chrome_integration_egtests[4727:73092] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[09:10:11:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.594 seconds).\n[09:10:11:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-17 21:10:11.791.\n[09:10:11:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 6.991 (6.998) seconds\n[09:10:11:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-17 21:10:11.792\n[09:10:11:INFO] [0317/211011.793287:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:11:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[09:10:21:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.261 seconds).\n[09:10:21:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[09:10:29:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (8.808 seconds).\n[09:10:29:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[09:10:35:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.534 seconds).\n[09:10:35:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[09:10:39:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.611 seconds).\n[09:10:39:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[09:10:48:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.064 seconds).\n[09:10:48:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-17 21:10:48.079.\n[09:10:48:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 36.278 (36.287) seconds\n[09:10:48:INFO] Test Suite 'FormInputTestCase' started at 2019-03-17 21:10:48.080\n[09:10:48:INFO] [0317/211048.081193:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:48:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[09:10:51:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.625 seconds).\n[09:10:51:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[09:10:53:INFO] [0317/211053.598055:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:10:55:INFO] 2019-03-17 21:10:55.088 ios_chrome_integration_egtests[4727:73092] API error: <_UIKBCompatInputView: 0x7f9bcc5b9970; frame = (0 0; 0 0); layer = <CALayer: 0x600001da1e20>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[09:10:55:INFO] 2019-03-17 21:10:55.088 ios_chrome_integration_egtests[4727:73092] API error: <_UIKBCompatInputView: 0x7f9bcc5b9970; frame = (0 0; 0 0); layer = <CALayer: 0x600001da1e20>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[09:10:59:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (7.818 seconds).\n[09:10:59:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-17 21:10:59.527.\n[09:10:59:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 11.443 (11.447) seconds\n[09:10:59:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-17 21:10:59.528\n[09:10:59:INFO] [0317/211059.529320:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:10:59:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[09:11:01:INFO] [0317/211101.440905:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:11:10:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (10.761 seconds).\n[09:11:10:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[09:11:13:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.642 seconds).\n[09:11:13:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[09:11:24:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.176 seconds).\n[09:11:24:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[09:11:28:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.728 seconds).\n[09:11:28:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[09:11:36:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.547 seconds).\n[09:11:36:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[09:11:42:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.098 seconds).\n[09:11:42:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-17 21:11:42.488.\n[09:11:42:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 42.953 (42.960) seconds\n[09:11:42:INFO] Test Suite 'NTPTilesTest' started at 2019-03-17 21:11:42.489\n[09:11:42:INFO] [0317/211142.490560:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:11:42:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[09:11:46:INFO] [0317/211146.184391:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:11:52:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.509 seconds).\n[09:11:52:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[09:12:15:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (23.796 seconds).\n[09:12:15:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-17 21:12:15.802.\n[09:12:15:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 33.305 (33.313) seconds\n[09:12:15:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-17 21:12:15.803\n[09:12:15:INFO] [0317/211215.804511:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:12:15:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[09:12:17:INFO] [0317/211217.714866:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:12:24:INFO] 2019-03-17 21:12:24.445 ios_chrome_integration_egtests[4727:73092] Attempting to type key 1.\n[09:12:24:INFO] 2019-03-17 21:12:24.546 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, numbers.\n[09:12:24:INFO] 2019-03-17 21:12:24.631 ios_chrome_integration_egtests[4727:73092] Tapping on key: 1.\n[09:12:24:INFO] 2019-03-17 21:12:24.727 ios_chrome_integration_egtests[4727:73092] Attempting to type key 2.\n[09:12:24:INFO] 2019-03-17 21:12:24.729 ios_chrome_integration_egtests[4727:73092] Tapping on key: 2.\n[09:12:24:INFO] 2019-03-17 21:12:24.797 ios_chrome_integration_egtests[4727:73092] Attempting to type key 7.\n[09:12:24:INFO] 2019-03-17 21:12:24.798 ios_chrome_integration_egtests[4727:73092] Tapping on key: 7.\n[09:12:24:INFO] [0317/211224.843800:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 3\n[09:12:24:INFO] 2019-03-17 21:12:24.865 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:24:INFO] 2019-03-17 21:12:24.867 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:24:INFO] 2019-03-17 21:12:24.976 ios_chrome_integration_egtests[4727:73092] Attempting to type key 0.\n[09:12:24:INFO] 2019-03-17 21:12:24.979 ios_chrome_integration_egtests[4727:73092] Tapping on key: 0.\n[09:12:25:INFO] 2019-03-17 21:12:25.052 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:25:INFO] 2019-03-17 21:12:25.054 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:25:INFO] 2019-03-17 21:12:25.163 ios_chrome_integration_egtests[4727:73092] Attempting to type key 0.\n[09:12:25:INFO] 2019-03-17 21:12:25.165 ios_chrome_integration_egtests[4727:73092] Tapping on key: 0.\n[09:12:25:INFO] 2019-03-17 21:12:25.240 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:25:INFO] 2019-03-17 21:12:25.242 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:25:INFO] 2019-03-17 21:12:25.306 ios_chrome_integration_egtests[4727:73092] Attempting to type key 1.\n[09:12:25:INFO] 2019-03-17 21:12:25.306 ios_chrome_integration_egtests[4727:73092] Tapping on key: 1.\n[09:12:25:INFO] 2019-03-17 21:12:25.371 ios_chrome_integration_egtests[4727:73092] Attempting to type key :.\n[09:12:25:INFO] 2019-03-17 21:12:25.373 ios_chrome_integration_egtests[4727:73092] Tapping on key: :.\n[09:12:25:INFO] [0317/211225.424566:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 7\n[09:12:25:INFO] 2019-03-17 21:12:25.438 ios_chrome_integration_egtests[4727:73092] Attempting to type key 6.\n[09:12:25:INFO] 2019-03-17 21:12:25.439 ios_chrome_integration_egtests[4727:73092] Tapping on key: 6.\n[09:12:25:INFO] 2019-03-17 21:12:25.504 ios_chrome_integration_egtests[4727:73092] Attempting to type key 2.\n[09:12:25:INFO] 2019-03-17 21:12:25.505 ios_chrome_integration_egtests[4727:73092] Tapping on key: 2.\n[09:12:25:INFO] 2019-03-17 21:12:25.571 ios_chrome_integration_egtests[4727:73092] Attempting to type key 9.\n[09:12:25:INFO] 2019-03-17 21:12:25.572 ios_chrome_integration_egtests[4727:73092] Tapping on key: 9.\n[09:12:25:INFO] 2019-03-17 21:12:25.672 ios_chrome_integration_egtests[4727:73092] Attempting to type key 7.\n[09:12:25:INFO] 2019-03-17 21:12:25.673 ios_chrome_integration_egtests[4727:73092] Tapping on key: 7.\n[09:12:25:INFO] 2019-03-17 21:12:25.744 ios_chrome_integration_egtests[4727:73092] Attempting to type key 4.\n[09:12:25:INFO] 2019-03-17 21:12:25.744 ios_chrome_integration_egtests[4727:73092] Tapping on key: 4.\n[09:12:25:INFO] 2019-03-17 21:12:25.805 ios_chrome_integration_egtests[4727:73092] Attempting to type key /.\n[09:12:25:INFO] 2019-03-17 21:12:25.806 ios_chrome_integration_egtests[4727:73092] Tapping on key: /.\n[09:12:25:INFO] 2019-03-17 21:12:25.867 ios_chrome_integration_egtests[4727:73092] Attempting to type key t.\n[09:12:26:INFO] 2019-03-17 21:12:26.174 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, letters.\n[09:12:26:INFO] 2019-03-17 21:12:26.225 ios_chrome_integration_egtests[4727:73092] Tapping on key: t.\n[09:12:26:INFO] 2019-03-17 21:12:26.282 ios_chrome_integration_egtests[4727:73092] Attempting to type key e.\n[09:12:26:INFO] 2019-03-17 21:12:26.283 ios_chrome_integration_egtests[4727:73092] Tapping on key: e.\n[09:12:26:INFO] 2019-03-17 21:12:26.341 ios_chrome_integration_egtests[4727:73092] Attempting to type key s.\n[09:12:26:INFO] 2019-03-17 21:12:26.343 ios_chrome_integration_egtests[4727:73092] Tapping on key: s.\n[09:12:26:INFO] 2019-03-17 21:12:26.402 ios_chrome_integration_egtests[4727:73092] Attempting to type key t.\n[09:12:26:INFO] 2019-03-17 21:12:26.402 ios_chrome_integration_egtests[4727:73092] Tapping on key: t.\n[09:12:26:INFO] 2019-03-17 21:12:26.464 ios_chrome_integration_egtests[4727:73092] Attempting to type key -.\n[09:12:26:INFO] 2019-03-17 21:12:26.567 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, numbers.\n[09:12:26:INFO] 2019-03-17 21:12:26.619 ios_chrome_integration_egtests[4727:73092] Tapping on key: -.\n[09:12:26:INFO] 2019-03-17 21:12:26.682 ios_chrome_integration_egtests[4727:73092] Attempting to type key p.\n[09:12:26:INFO] 2019-03-17 21:12:26.987 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, letters.\n[09:12:27:INFO] 2019-03-17 21:12:27.039 ios_chrome_integration_egtests[4727:73092] Tapping on key: p.\n[09:12:27:INFO] 2019-03-17 21:12:27.095 ios_chrome_integration_egtests[4727:73092] Attempting to type key a.\n[09:12:27:INFO] 2019-03-17 21:12:27.095 ios_chrome_integration_egtests[4727:73092] Tapping on key: a.\n[09:12:27:INFO] 2019-03-17 21:12:27.155 ios_chrome_integration_egtests[4727:73092] Attempting to type key g.\n[09:12:27:INFO] 2019-03-17 21:12:27.156 ios_chrome_integration_egtests[4727:73092] Tapping on key: g.\n[09:12:27:INFO] 2019-03-17 21:12:27.217 ios_chrome_integration_egtests[4727:73092] Attempting to type key e.\n[09:12:27:INFO] 2019-03-17 21:12:27.217 ios_chrome_integration_egtests[4727:73092] Tapping on key: e.\n[09:12:27:INFO] 2019-03-17 21:12:27.279 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:27:INFO] 2019-03-17 21:12:27.281 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:27:INFO] 2019-03-17 21:12:27.342 ios_chrome_integration_egtests[4727:73092] Attempting to type key h.\n[09:12:27:INFO] 2019-03-17 21:12:27.342 ios_chrome_integration_egtests[4727:73092] Tapping on key: h.\n[09:12:27:INFO] 2019-03-17 21:12:27.420 ios_chrome_integration_egtests[4727:73092] Attempting to type key t.\n[09:12:27:INFO] 2019-03-17 21:12:27.422 ios_chrome_integration_egtests[4727:73092] Tapping on key: t.\n[09:12:27:INFO] 2019-03-17 21:12:27.478 ios_chrome_integration_egtests[4727:73092] Attempting to type key m.\n[09:12:27:INFO] 2019-03-17 21:12:27.479 ios_chrome_integration_egtests[4727:73092] Tapping on key: m.\n[09:12:27:INFO] 2019-03-17 21:12:27.540 ios_chrome_integration_egtests[4727:73092] Attempting to type key l.\n[09:12:27:INFO] 2019-03-17 21:12:27.541 ios_chrome_integration_egtests[4727:73092] Tapping on key: l.\n[09:12:27:INFO] 2019-03-17 21:12:27.604 ios_chrome_integration_egtests[4727:73092] Attempting to type key\n[09:12:27:INFO] .\n[09:12:27:INFO] 2019-03-17 21:12:27.605 ios_chrome_integration_egtests[4727:73092] Tapping on key: Go.\n[09:12:33:INFO] 2019-03-17 21:12:33.471 ios_chrome_integration_egtests[4727:73092] Attempting to type key 1.\n[09:12:33:INFO] 2019-03-17 21:12:33.574 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, numbers.\n[09:12:33:INFO] 2019-03-17 21:12:33.626 ios_chrome_integration_egtests[4727:73092] Tapping on key: 1.\n[09:12:33:INFO] 2019-03-17 21:12:33.700 ios_chrome_integration_egtests[4727:73092] Attempting to type key 2.\n[09:12:33:INFO] 2019-03-17 21:12:33.701 ios_chrome_integration_egtests[4727:73092] Tapping on key: 2.\n[09:12:33:INFO] 2019-03-17 21:12:33.782 ios_chrome_integration_egtests[4727:73092] Attempting to type key 7.\n[09:12:33:INFO] 2019-03-17 21:12:33.783 ios_chrome_integration_egtests[4727:73092] Tapping on key: 7.\n[09:12:33:INFO] 2019-03-17 21:12:33.856 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:33:INFO] 2019-03-17 21:12:33.858 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:33:INFO] [0317/211233.928645:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 15\n[09:12:33:INFO] 2019-03-17 21:12:33.939 ios_chrome_integration_egtests[4727:73092] Attempting to type key 0.\n[09:12:33:INFO] 2019-03-17 21:12:33.941 ios_chrome_integration_egtests[4727:73092] Tapping on key: 0.\n[09:12:34:INFO] 2019-03-17 21:12:34.027 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:34:INFO] 2019-03-17 21:12:34.028 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:34:INFO] 2019-03-17 21:12:34.106 ios_chrome_integration_egtests[4727:73092] Attempting to type key 0.\n[09:12:34:INFO] 2019-03-17 21:12:34.106 ios_chrome_integration_egtests[4727:73092] Tapping on key: 0.\n[09:12:34:INFO] 2019-03-17 21:12:34.200 ios_chrome_integration_egtests[4727:73092] Attempting to type key ..\n[09:12:34:INFO] 2019-03-17 21:12:34.202 ios_chrome_integration_egtests[4727:73092] Tapping on key: ..\n[09:12:34:INFO] [0317/211234.257181:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 19\n[09:12:34:INFO] 2019-03-17 21:12:34.287 ios_chrome_integration_egtests[4727:73092] Attempting to type key 1.\n[09:12:34:INFO] 2019-03-17 21:12:34.287 ios_chrome_integration_egtests[4727:73092] Tapping on key: 1.\n[09:12:34:INFO] 2019-03-17 21:12:34.356 ios_chrome_integration_egtests[4727:73092] Attempting to type key :.\n[09:12:34:INFO] 2019-03-17 21:12:34.358 ios_chrome_integration_egtests[4727:73092] Tapping on key: :.\n[09:12:34:INFO] 2019-03-17 21:12:34.462 ios_chrome_integration_egtests[4727:73092] Attempting to type key 6.\n[09:12:34:INFO] 2019-03-17 21:12:34.464 ios_chrome_integration_egtests[4727:73092] Tapping on key: 6.\n[09:12:34:INFO] 2019-03-17 21:12:34.538 ios_chrome_integration_egtests[4727:73092] Attempting to type key 2.\n[09:12:34:INFO] 2019-03-17 21:12:34.539 ios_chrome_integration_egtests[4727:73092] Tapping on key: 2.\n[09:12:34:INFO] 2019-03-17 21:12:34.610 ios_chrome_integration_egtests[4727:73092] Attempting to type key 9.\n[09:12:34:INFO] 2019-03-17 21:12:34.611 ios_chrome_integration_egtests[4727:73092] Tapping on key: 9.\n[09:12:34:INFO] 2019-03-17 21:12:34.679 ios_chrome_integration_egtests[4727:73092] Attempting to type key 7.\n[09:12:34:INFO] 2019-03-17 21:12:34.680 ios_chrome_integration_egtests[4727:73092] Tapping on key: 7.\n[09:12:34:INFO] 2019-03-17 21:12:34.743 ios_chrome_integration_egtests[4727:73092] Attempting to type key 4.\n[09:12:34:INFO] 2019-03-17 21:12:34.743 ios_chrome_integration_egtests[4727:73092] Tapping on key: 4.\n[09:12:34:INFO] 2019-03-17 21:12:34.808 ios_chrome_integration_egtests[4727:73092] Attempting to type key /.\n[09:12:34:INFO] 2019-03-17 21:12:34.809 ios_chrome_integration_egtests[4727:73092] Tapping on key: /.\n[09:12:34:INFO] 2019-03-17 21:12:34.871 ios_chrome_integration_egtests[4727:73092] Attempting to type key t.\n[09:12:35:INFO] 2019-03-17 21:12:35.178 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, letters.\n[09:12:35:INFO] 2019-03-17 21:12:35.230 ios_chrome_integration_egtests[4727:73092] Tapping on key: t.\n[09:12:35:INFO] 2019-03-17 21:12:35.289 ios_chrome_integration_egtests[4727:73092] Attempting to type key e.\n[09:12:35:INFO] 2019-03-17 21:12:35.290 ios_chrome_integration_egtests[4727:73092] Tapping on key: e.\n[09:12:35:INFO] 2019-03-17 21:12:35.353 ios_chrome_integration_egtests[4727:73092] Attempting to type key s.\n[09:12:35:INFO] 2019-03-17 21:12:35.355 ios_chrome_integration_egtests[4727:73092] Tapping on key: s.\n[09:12:35:INFO] 2019-03-17 21:12:35.416 ios_chrome_integration_egtests[4727:73092] Attempting to type key t.\n[09:12:35:INFO] 2019-03-17 21:12:35.416 ios_chrome_integration_egtests[4727:73092] Tapping on key: t.\n[09:12:35:INFO] 2019-03-17 21:12:35.477 ios_chrome_integration_egtests[4727:73092] Attempting to type key -.\n[09:12:35:INFO] 2019-03-17 21:12:35.580 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, numbers.\n[09:12:35:INFO] 2019-03-17 21:12:35.632 ios_chrome_integration_egtests[4727:73092] Tapping on key: -.\n[09:12:35:INFO] 2019-03-17 21:12:35.695 ios_chrome_integration_egtests[4727:73092] Attempting to type key p.\n[09:12:36:INFO] 2019-03-17 21:12:36.000 ios_chrome_integration_egtests[4727:73092] Tapping on key: more, letters.\n[09:12:36:INFO] 2019-03-17 21:12:36.054 ios_chrome_integration_egtests[4727:73092] Tapping on key: p.\n[09:12:36:INFO] 2019-03-17 21:12:36.114 ios_chrome_integration_egtests[4727:73092] Attempting to type key a.\n[09:12:36:INFO] 2019-03-17 21:12:36.115 ios_chrome_integration_egtests[4727:73092] Tapping on key: a.\n[09:12:36:INFO] 2019-03-17 21:12:36.177 ios_chrome_integration_egtests[4727:73092] Attempting to type key g.\n[09:12:36:INFO] 2019-03-17 21:12:36.178 ios_chrome_integration_egtests[4727:73092] Tapping on key: g.\n[09:12:40:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (24.509 seconds).\n[09:12:40:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-17 21:12:40.321.\n[09:12:40:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 24.509 (24.518) seconds\n[09:12:40:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-17 21:12:40.322\n[09:12:40:INFO] [0317/211240.323589:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:12:40:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[09:12:43:INFO] [0317/211243.801725:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:12:57:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (17.528 seconds).\n[09:12:57:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[09:13:28:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (30.228 seconds).\n[09:13:28:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[09:13:45:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.146 seconds).\n[09:13:45:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[09:14:06:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (21.148 seconds).\n[09:14:06:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[09:14:20:INFO] [0317/211420.653993:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:14:20:INFO] [0317/211420.674849:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:14:20:INFO] [0317/211420.683306:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:14:23:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (17.358 seconds).\n[09:14:23:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[09:14:31:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (8.232 seconds).\n[09:14:31:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[09:14:38:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (6.997 seconds).\n[09:14:38:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[09:14:48:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (9.535 seconds).\n[09:14:48:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[09:15:18:INFO] [0317/211518.773274:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:18:INFO] [0317/211518.811635:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:18:INFO] [0317/211518.822182:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:18:INFO] [0317/211518.858892:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:21:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (33.404 seconds).\n[09:15:21:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[09:15:35:INFO] [0317/211535.155249:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:35:INFO] [0317/211535.169135:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:35:INFO] [0317/211535.173875:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:35:INFO] [0317/211535.211996:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[09:15:38:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (16.396 seconds).\n[09:15:38:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[09:15:53:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (14.750 seconds).\n[09:15:53:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-17 21:15:53.055.\n[09:15:53:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 192.722 (192.733) seconds\n[09:15:53:INFO] Test Suite 'UKMTestCase' started at 2019-03-17 21:15:53.056\n[09:15:53:INFO] [0317/211553.057612:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:15:53:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[09:15:59:INFO] 2019-03-17 21:15:59.633 ios_chrome_integration_egtests[4727:73092] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7f9bcde7e090; baseClass = UIButton; frame = (16 516; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x600003222d80>>, Touch Target: {86, 36}\n[09:16:00:INFO] [0317/211600.966887:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:16:01:INFO] [0317/211600.991936:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:16:01:INFO] [0317/211600.992127:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:16:01:INFO] [0317/211600.995056:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:16:01:INFO] [0317/211601.933100:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:16:12:INFO] [0317/211612.681125:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:16:22:INFO] [0317/211622.550989:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:16:22:INFO] [0317/211622.551094:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:16:23:INFO] [0317/211623.533638:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:16:23:INFO] [0317/211623.615820:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[09:16:34:INFO] [0317/211634.252213:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:16:38:INFO] [0317/211638.043047:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:16:39:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (46.857 seconds).\n[09:16:39:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[09:16:48:INFO] [0317/211648.022846:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:16:48:INFO] [0317/211648.025614:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:16:48:INFO] [0317/211648.034081:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:16:48:INFO] [0317/211648.034193:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:16:49:INFO] [0317/211649.003009:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:17:05:INFO] [0317/211705.179501:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:17:08:INFO] [0317/211708.972385:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:17:10:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (30.920 seconds).\n[09:17:10:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[09:17:18:INFO] [0317/211718.943099:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:18:INFO] [0317/211718.948382:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:18:INFO] [0317/211718.950436:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:17:18:INFO] [0317/211718.950535:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:17:19:INFO] [0317/211719.921366:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:17:30:INFO] [0317/211730.626794:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:17:30:INFO] [0317/211730.815152:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[09:17:30:INFO] [0317/211730.815244:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[09:17:34:INFO] [0317/211734.422756:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:17:36:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (25.453 seconds).\n[09:17:36:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[09:17:44:INFO] [0317/211744.415036:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:44:INFO] [0317/211744.419921:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:17:44:INFO] [0317/211744.423617:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:17:44:INFO] [0317/211744.423723:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:17:45:INFO] [0317/211745.401195:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:18:05:INFO] [0317/211805.314048:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:18:09:INFO] [0317/211809.114454:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:18:11:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (34.724 seconds).\n[09:18:11:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[09:18:19:INFO] [0317/211819.134772:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:18:19:INFO] [0317/211819.134878:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:18:19:INFO] [0317/211819.137005:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:18:19:INFO] [0317/211819.138862:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:18:20:INFO] [0317/211820.109671:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:18:50:INFO] [0317/211850.863773:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:18:54:INFO] [0317/211854.657525:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:19:00:INFO] [0317/211900.696797:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:19:00:INFO] [0317/211900.698718:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:19:00:INFO] [0317/211900.703315:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:19:00:INFO] [0317/211900.703426:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:19:01:INFO] [0317/211901.678664:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:12:INFO] [0317/211912.388602:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:19:12:INFO] [0317/211912.573103:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[09:19:12:INFO] [0317/211912.573197:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[09:19:16:INFO] [0317/211916.187414:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:19:18:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (67.042 seconds).\n[09:19:18:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[09:19:26:INFO] [0317/211926.149002:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:19:26:INFO] [0317/211926.155288:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:19:26:INFO] [0317/211926.157192:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:19:26:INFO] [0317/211926.157291:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:19:27:INFO] [0317/211927.131228:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:40:INFO] [0317/211940.169152:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:41:INFO] [0317/211941.529201:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:42:INFO] [0317/211942.878323:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:44:INFO] [0317/211944.207997:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:53:INFO] [0317/211953.433318:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:19:57:INFO] [0317/211957.226481:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:19:59:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (41.039 seconds).\n[09:19:59:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[09:20:07:INFO] [0317/212007.199446:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:20:07:INFO] [0317/212007.201394:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[09:20:07:INFO] [0317/212007.204200:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:20:07:INFO] [0317/212007.204305:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:20:08:INFO] [0317/212008.182338:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:18:INFO] [0317/212018.898580:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:20:28:INFO] [0317/212028.736515:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:20:28:INFO] [0317/212028.736616:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:20:29:INFO] [0317/212029.710657:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:20:29:INFO] [0317/212029.784538:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[09:20:40:INFO] [0317/212040.435907:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:20:44:INFO] [0317/212044.227622:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[09:20:46:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (47.001 seconds).\n[09:20:46:INFO] Test Suite 'UKMTestCase' passed at 2019-03-17 21:20:46.099.\n[09:20:46:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 293.035 (293.043) seconds\n[09:20:46:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-17 21:20:46.100.\n[09:20:46:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 787.377 (789.401) seconds\n[09:20:46:INFO] Test Suite 'All tests' passed at 2019-03-17 21:20:46.101.\n[09:20:46:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 787.377 (789.403) seconds\n[09:20:46:INFO] \n[09:20:46:INFO] \n[09:20:46:INFO] Test session results and logs:\n[09:20:46:INFO] \t/Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-crgrfchyrprdngbolsmsfgxnwtqy/Logs/Test/Test-Transient Testing-2019.03.17_21-07-08--0700.xcresult\n[09:20:46:INFO] \n[09:20:46:INFO] 2019-03-17 21:20:46.544 xcodebuild[4612:72005] [MT] IDETestOperationsObserverDebug: 817.948 elapsed -- Testing started completed.\n[09:20:46:INFO] 2019-03-17 21:20:46.544 xcodebuild[4612:72005] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[09:20:46:INFO] 2019-03-17 21:20:46.544 xcodebuild[4612:72005] [MT] IDETestOperationsObserverDebug: 817.948 sec, +817.948 sec -- end\n[09:20:46:INFO] ** TEST EXECUTE SUCCEEDED **\n[09:20:46:INFO] \n[09:20:52:DEBUG] Finished print_process_output.\n[09:20:52:INFO] Waiting for test process to terminate.\n[09:20:52:INFO] Test process terminated.\n[09:20:52:DEBUG] Setting sigterm handler.\n[09:20:52:DEBUG] Stdout flushed after test process.\n[09:20:52:DEBUG] Processing test results.\n[09:20:52:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0\n\n[09:20:52:DEBUG] Extracting test data.\n[09:20:52:DEBUG] Retrieving crash reports.\n[09:20:52:DEBUG] Retrieving derived data.\n[09:20:52:DEBUG] Making desktop screenshots.\n[09:20:52:DEBUG] Killing simulators.\n[09:20:57:DEBUG] Wiping simulator.\nDevice wiped.\n[09:20:59:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "ac333de918b8b9b70b92c34d2f3125cd7f9449e5", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43a7cc861143b311", "server_versions": [ "4201-b04f7f8" ], "started_ts": "2019-03-18T04:06:47.894627", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-beta-simulator", "buildnumber:3845", "data:57d7ba6caf02aecc83b4bda06d121e97123da155", "device_type:iPhone 5s", "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 5s 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": "43a7cc861143b310", "try_number": "1" } ] }