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": [ "8" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-E5-2697_v2" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "none" ] }, { "key": "id", "value": [ "vm928-m4" ] }, { "key": "mac_model", "value": [ "VMware7,1" ] }, { "key": "machine_type", "value": [ "n1-highcpu-8" ] }, { "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": [ "4216-cf4a3cd" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-4" ] } ], "bot_id": "vm928-m4", "bot_version": "c7f0c187ee2160053ce935aa86119b526086da1e165dea02547e2f5a35dc9e3f", "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-25T15:04:20.569972", "costs_usd": [ 0.2051705559401612 ], "created_ts": "2019-03-25T14:48:40.050422", "duration": 868.8091690540314, "modified_ts": "2019-03-25T15:04:20.569972", "name": "ios_chrome_integration_egtests (iPhone 6s iOS 11.4)/Mac-10.13.6/6eb13c8d06/ios-slimnav/114", "output": "[I 2019-03-25 07:49:03] About to install Xcode 10b61 in Xcode.app for ios\n[07:49:04:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\nDevice wiped.\n[07:49:12:DEBUG] Setting sigterm handler.\n[07:49:14:INFO] User defaults from command line:\n[07:49:14:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/0E00F2A1-B65A-4A5B-AD6C-344CB70FA2F0\n[07:49:14:INFO] \n[07:49:15:INFO] Testing started on 'iPhone 6s'\n[07:49:15:INFO] 2019-03-25 07:49:15.260 xcodebuild[25597:1277462] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[07:49:15:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-dtrddzrmdgcdsxdblvkhvgqshicm/Logs/Test/Test-Transient Testing-2019.03.25_07-49-15--0700.xcresult/1_Test/Diagnostics/ios_chrome_integration_egtests_module-6FE735E9-0520-4620-BDE6-613167A64389/ios_chrome_integration_egtests_module-F81BA619-0D4C-4A41-A7D8-D7815ECA321C/Session-ios_chrome_integration_egtests_module-2019-03-25_074915-9kLObM.log\n[07:49:15:INFO] 2019-03-25 07:49:15.261 xcodebuild[25597:1277451] [MT] IDETestOperationsObserverDebug: (76DA0C7E-061A-405B-BDF5-078A969C46D6) Beginning test session ios_chrome_integration_egtests_module-76DA0C7E-061A-405B-BDF5-078A969C46D6 at 2019-03-25 07:49:15.261 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f80cd46fd30> {\n[07:49:15:INFO] \t\tSimDevice: iPhone 6s (646CC423-AF1E-45DA-A393-8709A31A07E9, iOS 11.4, Shutdown)\n[07:49:15:INFO] } (11.4 (15F79))\n[07:49:32:INFO] 2019-03-25 07:49:32.659 ios_chrome_integration_egtests[25715:1278901] Crash handler setup started.\n[07:49:32:INFO] 2019-03-25 07:49:32.660 ios_chrome_integration_egtests[25715:1278901] Crash handler setup completed.\n[07:49:32:INFO] 2019-03-25 07:49:32.660 ios_chrome_integration_egtests[25715:1278901] Enabling accessibility for automation on Simulator.\n[07:49:32:INFO] objc[25715]: Class VCWeakObjectHolder is implemented in both /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/Frameworks/ViceroyTrace.framework/ViceroyTrace (0x14497f4d0) and /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/AVConference (0x144795e38). One of the two will be used. Which one is undefined.\n[07:49:35:INFO] 2019-03-25 07:49:35.615 ios_chrome_integration_egtests[25715:1278901] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fc85e890a00>.\n[07:49:36:INFO] [0325/074936.735462:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/646CC423-AF1E-45DA-A393-8709A31A07E9/data/Containers/Data/Application/08EA6F10-79ED-44EF-A37C-034B3C4975C5/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/646CC423-AF1E-45DA-A393-8709A31A07E9/data/Containers/Data/Application/08EA6F10-79ED-44EF-A37C-034B3C4975C5/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[07:49:42:INFO] Test Suite 'All tests' started at 2019-03-25 07:49:42.862\n[07:49:42:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-25 07:49:42.863\n[07:49:42:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-25 07:49:42.863\n[07:49:42:INFO] [0325/074942.871009:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:49:44:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[07:49:48:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (3.733 seconds).\n[07:49:48:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-25 07:49:48.463.\n[07:49:48:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 3.733 (5.600) seconds\n[07:49:48:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-25 07:49:48.464\n[07:49:48:INFO] [0325/074948.465761:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:49:48:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[07:49:50:INFO] [0325/074950.537604:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:49:56:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (8.011 seconds).\n[07:49:56:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[07:49:58:INFO] [0325/074958.487340:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:04:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (8.067 seconds).\n[07:50:04:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[07:50:06:INFO] [0325/075006.563971:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:14:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.172 seconds).\n[07:50:14:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[07:50:16:INFO] [0325/075016.779521:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:22:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.029 seconds).\n[07:50:22:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[07:50:24:INFO] [0325/075024.743104:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:32:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (10.081 seconds).\n[07:50:32:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[07:50:34:INFO] [0325/075034.918531:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:39:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (6.310 seconds).\n[07:50:39:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[07:50:41:INFO] [0325/075041.220558:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:50:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (11.049 seconds).\n[07:50:50:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[07:50:52:INFO] [0325/075052.265369:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:50:58:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.066 seconds).\n[07:50:58:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[07:51:00:INFO] [0325/075100.260746:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:51:08:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (10.112 seconds).\n[07:51:08:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-25 07:51:08.375.\n[07:51:08:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 79.898 (79.911) seconds\n[07:51:08:INFO] Test Suite 'CookiesTestCase' started at 2019-03-25 07:51:08.376\n[07:51:08:INFO] [0325/075108.377088:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:51:08:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[07:51:10:INFO] [0325/075110.494863:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:51:25:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.176 seconds).\n[07:51:25:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[07:51:39:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.068 seconds).\n[07:51:39:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[07:51:48:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.549 seconds).\n[07:51:48:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[07:52:01:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.204 seconds).\n[07:52:01:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[07:52:15:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (13.885 seconds).\n[07:52:15:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-25 07:52:15.264.\n[07:52:15:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 66.881 (66.888) seconds\n[07:52:15:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-25 07:52:15.265\n[07:52:15:INFO] [0325/075215.266212:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:52:15:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[07:52:16:INFO] 2019-03-25 07:52:16.833 ios_chrome_integration_egtests[25715:1278901] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[07:52:18:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.445 seconds).\n[07:52:18:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[07:52:20:INFO] 2019-03-25 07:52:20.556 ios_chrome_integration_egtests[25715:1278901] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[07:52:22:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.712 seconds).\n[07:52:22:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-25 07:52:22.429.\n[07:52:22:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 7.157 (7.164) seconds\n[07:52:22:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-25 07:52:22.430\n[07:52:22:INFO] [0325/075222.431187:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:52:22:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[07:52:32:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.653 seconds).\n[07:52:32:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[07:52:41:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (9.480 seconds).\n[07:52:41:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[07:52:47:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.787 seconds).\n[07:52:47:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[07:52:51:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.657 seconds).\n[07:52:51:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[07:53:00:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.412 seconds).\n[07:53:00:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-25 07:53:00.426.\n[07:53:00:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 37.988 (37.997) seconds\n[07:53:00:INFO] Test Suite 'FormInputTestCase' started at 2019-03-25 07:53:00.427\n[07:53:00:INFO] [0325/075300.428636:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:53:00:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[07:53:04:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.688 seconds).\n[07:53:04:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[07:53:06:INFO] [0325/075306.192978:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:53:07:INFO] 2019-03-25 07:53:07.749 ios_chrome_integration_egtests[25715:1278901] API error: <_UIKBCompatInputView: 0x7fc866f018e0; frame = (0 0; 0 0); layer = <CALayer: 0x61c000833c00>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[07:53:07:INFO] 2019-03-25 07:53:07.749 ios_chrome_integration_egtests[25715:1278901] API error: <_UIKBCompatInputView: 0x7fc866f018e0; frame = (0 0; 0 0); layer = <CALayer: 0x61c000833c00>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[07:53:12:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (8.556 seconds).\n[07:53:12:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-25 07:53:12.677.\n[07:53:12:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 12.245 (12.250) seconds\n[07:53:12:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-25 07:53:12.678\n[07:53:12:INFO] [0325/075312.679362:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:53:12:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[07:53:14:INFO] [0325/075314.735386:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:53:23:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (10.855 seconds).\n[07:53:23:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[07:53:27:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.622 seconds).\n[07:53:27:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[07:53:37:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.248 seconds).\n[07:53:37:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[07:53:42:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.911 seconds).\n[07:53:42:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[07:53:49:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.529 seconds).\n[07:53:49:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[07:53:55:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.009 seconds).\n[07:53:55:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-25 07:53:55.860.\n[07:53:55:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 43.175 (43.182) seconds\n[07:53:55:INFO] Test Suite 'NTPTilesTest' started at 2019-03-25 07:53:55.861\n[07:53:55:INFO] [0325/075355.862565:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:53:55:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[07:53:59:INFO] [0325/075359.543425:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:54:05:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.408 seconds).\n[07:54:05:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[07:54:28:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (23.039 seconds).\n[07:54:28:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-25 07:54:28.317.\n[07:54:28:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 32.448 (32.455) seconds\n[07:54:28:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-25 07:54:28.317\n[07:54:28:INFO] [0325/075428.318605:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:54:28:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[07:54:30:INFO] [0325/075430.471930:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:54:37:INFO] 2019-03-25 07:54:37.598 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 1.\n[07:54:37:INFO] 2019-03-25 07:54:37.702 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, numbers.\n[07:54:37:INFO] 2019-03-25 07:54:37.786 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 1.\n[07:54:37:INFO] 2019-03-25 07:54:37.893 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 2.\n[07:54:37:INFO] 2019-03-25 07:54:37.894 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 2.\n[07:54:37:INFO] 2019-03-25 07:54:37.964 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 7.\n[07:54:37:INFO] 2019-03-25 07:54:37.965 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 7.\n[07:54:38:INFO] 2019-03-25 07:54:38.034 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:38:INFO] 2019-03-25 07:54:38.036 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:38:INFO] [0325/075438.094611:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 5\n[07:54:38:INFO] 2019-03-25 07:54:38.106 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 0.\n[07:54:38:INFO] 2019-03-25 07:54:38.107 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 0.\n[07:54:38:INFO] 2019-03-25 07:54:38.173 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:38:INFO] 2019-03-25 07:54:38.174 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:38:INFO] 2019-03-25 07:54:38.240 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 0.\n[07:54:38:INFO] 2019-03-25 07:54:38.241 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 0.\n[07:54:38:INFO] [0325/075438.295083:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 7\n[07:54:38:INFO] 2019-03-25 07:54:38.306 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:38:INFO] 2019-03-25 07:54:38.308 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:38:INFO] 2019-03-25 07:54:38.376 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 1.\n[07:54:38:INFO] 2019-03-25 07:54:38.376 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 1.\n[07:54:38:INFO] 2019-03-25 07:54:38.445 ios_chrome_integration_egtests[25715:1278901] Attempting to type key :.\n[07:54:38:INFO] 2019-03-25 07:54:38.447 ios_chrome_integration_egtests[25715:1278901] Tapping on key: :.\n[07:54:38:INFO] [0325/075438.491738:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 9\n[07:54:38:INFO] 2019-03-25 07:54:38.518 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 6.\n[07:54:38:INFO] 2019-03-25 07:54:38.519 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 6.\n[07:54:38:INFO] 2019-03-25 07:54:38.591 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 2.\n[07:54:38:INFO] 2019-03-25 07:54:38.592 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 2.\n[07:54:38:INFO] 2019-03-25 07:54:38.660 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 4.\n[07:54:38:INFO] 2019-03-25 07:54:38.661 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 4.\n[07:54:38:INFO] 2019-03-25 07:54:38.730 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 8.\n[07:54:38:INFO] 2019-03-25 07:54:38.731 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 8.\n[07:54:38:INFO] 2019-03-25 07:54:38.801 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 9.\n[07:54:38:INFO] 2019-03-25 07:54:38.802 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 9.\n[07:54:38:INFO] 2019-03-25 07:54:38.868 ios_chrome_integration_egtests[25715:1278901] Attempting to type key /.\n[07:54:38:INFO] 2019-03-25 07:54:38.869 ios_chrome_integration_egtests[25715:1278901] Tapping on key: /.\n[07:54:38:INFO] 2019-03-25 07:54:38.933 ios_chrome_integration_egtests[25715:1278901] Attempting to type key t.\n[07:54:39:INFO] 2019-03-25 07:54:39.239 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, letters.\n[07:54:39:INFO] 2019-03-25 07:54:39.292 ios_chrome_integration_egtests[25715:1278901] Tapping on key: t.\n[07:54:39:INFO] 2019-03-25 07:54:39.362 ios_chrome_integration_egtests[25715:1278901] Attempting to type key e.\n[07:54:39:INFO] 2019-03-25 07:54:39.363 ios_chrome_integration_egtests[25715:1278901] Tapping on key: e.\n[07:54:39:INFO] 2019-03-25 07:54:39.424 ios_chrome_integration_egtests[25715:1278901] Attempting to type key s.\n[07:54:39:INFO] 2019-03-25 07:54:39.425 ios_chrome_integration_egtests[25715:1278901] Tapping on key: s.\n[07:54:39:INFO] 2019-03-25 07:54:39.489 ios_chrome_integration_egtests[25715:1278901] Attempting to type key t.\n[07:54:39:INFO] 2019-03-25 07:54:39.489 ios_chrome_integration_egtests[25715:1278901] Tapping on key: t.\n[07:54:39:INFO] 2019-03-25 07:54:39.551 ios_chrome_integration_egtests[25715:1278901] Attempting to type key -.\n[07:54:39:INFO] 2019-03-25 07:54:39.654 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, numbers.\n[07:54:39:INFO] 2019-03-25 07:54:39.708 ios_chrome_integration_egtests[25715:1278901] Tapping on key: -.\n[07:54:39:INFO] 2019-03-25 07:54:39.777 ios_chrome_integration_egtests[25715:1278901] Attempting to type key p.\n[07:54:40:INFO] 2019-03-25 07:54:40.083 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, letters.\n[07:54:40:INFO] 2019-03-25 07:54:40.137 ios_chrome_integration_egtests[25715:1278901] Tapping on key: p.\n[07:54:40:INFO] 2019-03-25 07:54:40.201 ios_chrome_integration_egtests[25715:1278901] Attempting to type key a.\n[07:54:40:INFO] 2019-03-25 07:54:40.201 ios_chrome_integration_egtests[25715:1278901] Tapping on key: a.\n[07:54:40:INFO] 2019-03-25 07:54:40.265 ios_chrome_integration_egtests[25715:1278901] Attempting to type key g.\n[07:54:40:INFO] 2019-03-25 07:54:40.266 ios_chrome_integration_egtests[25715:1278901] Tapping on key: g.\n[07:54:40:INFO] 2019-03-25 07:54:40.329 ios_chrome_integration_egtests[25715:1278901] Attempting to type key e.\n[07:54:40:INFO] 2019-03-25 07:54:40.330 ios_chrome_integration_egtests[25715:1278901] Tapping on key: e.\n[07:54:40:INFO] 2019-03-25 07:54:40.391 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:40:INFO] 2019-03-25 07:54:40.393 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:40:INFO] 2019-03-25 07:54:40.456 ios_chrome_integration_egtests[25715:1278901] Attempting to type key h.\n[07:54:40:INFO] 2019-03-25 07:54:40.457 ios_chrome_integration_egtests[25715:1278901] Tapping on key: h.\n[07:54:40:INFO] 2019-03-25 07:54:40.519 ios_chrome_integration_egtests[25715:1278901] Attempting to type key t.\n[07:54:40:INFO] 2019-03-25 07:54:40.519 ios_chrome_integration_egtests[25715:1278901] Tapping on key: t.\n[07:54:40:INFO] 2019-03-25 07:54:40.581 ios_chrome_integration_egtests[25715:1278901] Attempting to type key m.\n[07:54:40:INFO] 2019-03-25 07:54:40.582 ios_chrome_integration_egtests[25715:1278901] Tapping on key: m.\n[07:54:40:INFO] 2019-03-25 07:54:40.643 ios_chrome_integration_egtests[25715:1278901] Attempting to type key l.\n[07:54:40:INFO] 2019-03-25 07:54:40.644 ios_chrome_integration_egtests[25715:1278901] Tapping on key: l.\n[07:54:40:INFO] 2019-03-25 07:54:40.702 ios_chrome_integration_egtests[25715:1278901] Attempting to type key\n[07:54:40:INFO] .\n[07:54:40:INFO] 2019-03-25 07:54:40.703 ios_chrome_integration_egtests[25715:1278901] Tapping on key: Go.\n[07:54:46:INFO] 2019-03-25 07:54:46.884 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 1.\n[07:54:46:INFO] 2019-03-25 07:54:46.987 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, numbers.\n[07:54:47:INFO] 2019-03-25 07:54:47.040 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 1.\n[07:54:47:INFO] 2019-03-25 07:54:47.121 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 2.\n[07:54:47:INFO] 2019-03-25 07:54:47.122 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 2.\n[07:54:47:INFO] [0325/075447.167848:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 13\n[07:54:47:INFO] 2019-03-25 07:54:47.196 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 7.\n[07:54:47:INFO] 2019-03-25 07:54:47.198 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 7.\n[07:54:47:INFO] 2019-03-25 07:54:47.271 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:47:INFO] 2019-03-25 07:54:47.273 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:47:INFO] 2019-03-25 07:54:47.345 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 0.\n[07:54:47:INFO] 2019-03-25 07:54:47.346 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 0.\n[07:54:47:INFO] 2019-03-25 07:54:47.419 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:47:INFO] 2019-03-25 07:54:47.421 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:47:INFO] 2019-03-25 07:54:47.493 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 0.\n[07:54:47:INFO] 2019-03-25 07:54:47.494 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 0.\n[07:54:47:INFO] 2019-03-25 07:54:47.565 ios_chrome_integration_egtests[25715:1278901] Attempting to type key ..\n[07:54:47:INFO] 2019-03-25 07:54:47.566 ios_chrome_integration_egtests[25715:1278901] Tapping on key: ..\n[07:54:47:INFO] 2019-03-25 07:54:47.640 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 1.\n[07:54:47:INFO] 2019-03-25 07:54:47.642 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 1.\n[07:54:47:INFO] 2019-03-25 07:54:47.714 ios_chrome_integration_egtests[25715:1278901] Attempting to type key :.\n[07:54:47:INFO] 2019-03-25 07:54:47.715 ios_chrome_integration_egtests[25715:1278901] Tapping on key: :.\n[07:54:47:INFO] 2019-03-25 07:54:47.790 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 6.\n[07:54:47:INFO] 2019-03-25 07:54:47.790 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 6.\n[07:54:47:INFO] 2019-03-25 07:54:47.863 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 2.\n[07:54:47:INFO] 2019-03-25 07:54:47.864 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 2.\n[07:54:47:INFO] 2019-03-25 07:54:47.937 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 4.\n[07:54:47:INFO] 2019-03-25 07:54:47.937 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 4.\n[07:54:48:INFO] 2019-03-25 07:54:48.011 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 8.\n[07:54:48:INFO] 2019-03-25 07:54:48.011 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 8.\n[07:54:48:INFO] 2019-03-25 07:54:48.082 ios_chrome_integration_egtests[25715:1278901] Attempting to type key 9.\n[07:54:48:INFO] 2019-03-25 07:54:48.083 ios_chrome_integration_egtests[25715:1278901] Tapping on key: 9.\n[07:54:48:INFO] 2019-03-25 07:54:48.151 ios_chrome_integration_egtests[25715:1278901] Attempting to type key /.\n[07:54:48:INFO] 2019-03-25 07:54:48.152 ios_chrome_integration_egtests[25715:1278901] Tapping on key: /.\n[07:54:48:INFO] 2019-03-25 07:54:48.216 ios_chrome_integration_egtests[25715:1278901] Attempting to type key t.\n[07:54:48:INFO] 2019-03-25 07:54:48.524 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, letters.\n[07:54:48:INFO] 2019-03-25 07:54:48.578 ios_chrome_integration_egtests[25715:1278901] Tapping on key: t.\n[07:54:48:INFO] 2019-03-25 07:54:48.643 ios_chrome_integration_egtests[25715:1278901] Attempting to type key e.\n[07:54:48:INFO] 2019-03-25 07:54:48.643 ios_chrome_integration_egtests[25715:1278901] Tapping on key: e.\n[07:54:48:INFO] 2019-03-25 07:54:48.709 ios_chrome_integration_egtests[25715:1278901] Attempting to type key s.\n[07:54:48:INFO] 2019-03-25 07:54:48.710 ios_chrome_integration_egtests[25715:1278901] Tapping on key: s.\n[07:54:48:INFO] 2019-03-25 07:54:48.775 ios_chrome_integration_egtests[25715:1278901] Attempting to type key t.\n[07:54:48:INFO] 2019-03-25 07:54:48.775 ios_chrome_integration_egtests[25715:1278901] Tapping on key: t.\n[07:54:48:INFO] 2019-03-25 07:54:48.841 ios_chrome_integration_egtests[25715:1278901] Attempting to type key -.\n[07:54:48:INFO] 2019-03-25 07:54:48.944 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, numbers.\n[07:54:48:INFO] 2019-03-25 07:54:48.998 ios_chrome_integration_egtests[25715:1278901] Tapping on key: -.\n[07:54:49:INFO] 2019-03-25 07:54:49.070 ios_chrome_integration_egtests[25715:1278901] Attempting to type key p.\n[07:54:49:INFO] 2019-03-25 07:54:49.378 ios_chrome_integration_egtests[25715:1278901] Tapping on key: more, letters.\n[07:54:49:INFO] 2019-03-25 07:54:49.432 ios_chrome_integration_egtests[25715:1278901] Tapping on key: p.\n[07:54:49:INFO] 2019-03-25 07:54:49.498 ios_chrome_integration_egtests[25715:1278901] Attempting to type key a.\n[07:54:49:INFO] 2019-03-25 07:54:49.499 ios_chrome_integration_egtests[25715:1278901] Tapping on key: a.\n[07:54:49:INFO] 2019-03-25 07:54:49.564 ios_chrome_integration_egtests[25715:1278901] Attempting to type key g.\n[07:54:49:INFO] 2019-03-25 07:54:49.565 ios_chrome_integration_egtests[25715:1278901] Tapping on key: g.\n[07:54:53:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (25.611 seconds).\n[07:54:53:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-25 07:54:53.935.\n[07:54:53:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 25.611 (25.618) seconds\n[07:54:53:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-25 07:54:53.936\n[07:54:53:INFO] [0325/075453.937358:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:54:53:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[07:54:57:INFO] [0325/075457.411218:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[07:55:12:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (18.716 seconds).\n[07:55:12:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[07:55:42:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (29.852 seconds).\n[07:55:42:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[07:56:00:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.840 seconds).\n[07:56:00:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[07:56:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (21.980 seconds).\n[07:56:22:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[07:56:36:INFO] [0325/075636.733302:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[07:56:37:INFO] [0325/075637.076068:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[07:56:40:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (18.295 seconds).\n[07:56:40:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[07:56:48:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (8.273 seconds).\n[07:56:48:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[07:56:55:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.032 seconds).\n[07:56:55:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[07:57:06:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (10.257 seconds).\n[07:57:06:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[07:57:37:INFO] [0325/075737.356152:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[07:57:37:INFO] [0325/075737.696318:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[07:57:41:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (35.083 seconds).\n[07:57:41:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[07:57:54:INFO] [0325/075754.638617:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[07:57:54:INFO] [0325/075754.977546:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[07:57:58:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (17.251 seconds).\n[07:57:58:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[07:58:13:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (14.911 seconds).\n[07:58:13:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-25 07:58:13.437.\n[07:58:13:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 199.490 (199.501) seconds\n[07:58:13:INFO] Test Suite 'UKMTestCase' started at 2019-03-25 07:58:13.438\n[07:58:13:INFO] [0325/075813.439135:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:58:13:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[07:58:20:INFO] 2019-03-25 07:58:20.451 ios_chrome_integration_egtests[25715:1278901] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fc86186d530; baseClass = UIButton; frame = (16 615; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x60c0004e2900>>, Touch Target: {86, 36}\n[07:58:21:INFO] [0325/075821.812332:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[07:58:21:INFO] [0325/075821.832664:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[07:58:21:INFO] [0325/075821.847211:ERROR:account_tracker.cc(264)] OnOAuthError\n[07:58:21:INFO] [0325/075821.847366:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[07:58:22:INFO] [0325/075822.832258:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[07:58:34:INFO] [0325/075834.148012:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[07:58:44:INFO] [0325/075844.435750:ERROR:account_tracker.cc(264)] OnOAuthError\n[07:58:44:INFO] [0325/075844.435898:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[07:58:45:INFO] [0325/075845.458951:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[07:58:45:INFO] [0325/075845.532501:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[07:58:56:INFO] [0325/075856.733830:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[07:58:56:INFO] [0325/075856.939608:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[07:58:56:INFO] [0325/075856.939776:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[07:59:00:INFO] [0325/075900.578191:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:59:02:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (49.032 seconds).\n[07:59:02:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[07:59:10:INFO] [0325/075910.942482:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[07:59:10:INFO] [0325/075910.944037:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[07:59:10:INFO] [0325/075910.958252:ERROR:account_tracker.cc(264)] OnOAuthError\n[07:59:10:INFO] [0325/075910.958374:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[07:59:11:INFO] [0325/075911.987965:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[07:59:28:INFO] [0325/075928.527497:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[07:59:28:INFO] [0325/075928.697708:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[07:59:28:INFO] [0325/075928.697865:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[07:59:32:INFO] [0325/075932.371929:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[07:59:34:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (31.785 seconds).\n[07:59:34:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[07:59:42:INFO] [0325/075942.758178:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[07:59:42:INFO] [0325/075942.759665:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[07:59:42:INFO] [0325/075942.777006:ERROR:account_tracker.cc(264)] OnOAuthError\n[07:59:42:INFO] [0325/075942.777157:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[07:59:43:INFO] [0325/075943.800971:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[07:59:55:INFO] [0325/075955.078417:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[07:59:55:INFO] [0325/075955.253359:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[07:59:55:INFO] [0325/075955.253522:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[07:59:58:INFO] [0325/075958.935849:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:00:00:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (26.571 seconds).\n[08:00:00:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[08:00:09:INFO] [0325/080009.452557:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:00:09:INFO] [0325/080009.454056:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:00:09:INFO] [0325/080009.473610:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:00:09:INFO] [0325/080009.473751:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:00:10:INFO] [0325/080010.489610:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:00:30:INFO] [0325/080030.596681:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:00:30:INFO] [0325/080030.802769:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[08:00:30:INFO] [0325/080030.802922:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[08:00:34:INFO] [0325/080034.440903:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:00:36:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (35.544 seconds).\n[08:00:36:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[08:00:44:INFO] [0325/080044.864085:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:00:44:INFO] [0325/080044.865582:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:00:44:INFO] [0325/080044.882631:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:00:44:INFO] [0325/080044.882772:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:00:45:INFO] [0325/080045.904620:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:01:17:INFO] [0325/080117.932094:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:01:21:INFO] [0325/080121.770323:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:01:28:INFO] [0325/080128.220264:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:01:28:INFO] [0325/080128.221802:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:01:28:INFO] [0325/080128.239855:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:01:28:INFO] [0325/080128.240001:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:01:29:INFO] [0325/080129.271241:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:01:40:INFO] [0325/080140.541574:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:01:40:INFO] [0325/080140.710735:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[08:01:40:INFO] [0325/080140.710891:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[08:01:44:INFO] [0325/080144.384352:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:01:46:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (69.896 seconds).\n[08:01:46:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[08:01:54:INFO] [0325/080154.754230:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:01:54:INFO] [0325/080154.755768:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:01:54:INFO] [0325/080154.777162:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:01:54:INFO] [0325/080154.777309:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:01:55:INFO] [0325/080155.792944:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:02:09:INFO] [0325/080209.478706:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:02:10:INFO] [0325/080210.911329:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:02:12:INFO] [0325/080212.321108:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:02:13:INFO] [0325/080213.745843:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:02:23:INFO] [0325/080223.540479:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:02:23:INFO] [0325/080223.704325:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[08:02:23:INFO] [0325/080223.704483:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[08:02:27:INFO] [0325/080227.383698:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:02:29:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (43.002 seconds).\n[08:02:29:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[08:02:37:INFO] [0325/080237.845987:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:02:37:INFO] [0325/080237.847473:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag.\n[08:02:37:INFO] [0325/080237.868188:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:02:37:INFO] [0325/080237.868332:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:02:38:INFO] [0325/080238.885750:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:02:50:INFO] [0325/080250.157089:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:02:50:INFO] [0325/080250.327307:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[08:02:50:INFO] [0325/080250.327464:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[08:03:00:INFO] [0325/080300.458711:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:03:00:INFO] [0325/080300.458858:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:03:01:INFO] [0325/080301.483930:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:03:01:INFO] [0325/080301.560484:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[08:03:12:INFO] [0325/080312.701630:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:03:12:INFO] [0325/080312.881502:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[08:03:12:INFO] [0325/080312.881658:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[08:03:16:INFO] [0325/080316.567526:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:03:18:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (49.183 seconds).\n[08:03:18:INFO] Test Suite 'UKMTestCase' passed at 2019-03-25 08:03:18.457.\n[08:03:18:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 305.011 (305.020) seconds\n[08:03:18:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-25 08:03:18.458.\n[08:03:18:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 813.635 (815.595) seconds\n[08:03:18:INFO] Test Suite 'All tests' passed at 2019-03-25 08:03:18.459.\n[08:03:18:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 813.635 (815.597) seconds\n[08:03:18:INFO] \n[08:03:18:INFO] \n[08:03:18:INFO] Test session results and logs:\n[08:03:18:INFO] \t/Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-dtrddzrmdgcdsxdblvkhvgqshicm/Logs/Test/Test-Transient Testing-2019.03.25_07-49-15--0700.xcresult\n[08:03:18:INFO] \n[08:03:18:INFO] 2019-03-25 08:03:18.765 xcodebuild[25597:1277451] [MT] IDETestOperationsObserverDebug: 843.591 elapsed -- Testing started completed.\n[08:03:18:INFO] 2019-03-25 08:03:18.765 xcodebuild[25597:1277451] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[08:03:18:INFO] 2019-03-25 08:03:18.765 xcodebuild[25597:1277451] [MT] IDETestOperationsObserverDebug: 843.591 sec, +843.591 sec -- end\n[08:03:18:INFO] ** TEST EXECUTE SUCCEEDED **\n[08:03:18:INFO] \n[08:03:23:DEBUG] Finished print_process_output.\n[08:03:23:INFO] Waiting for test process to terminate.\n[08:03:23:INFO] Test process terminated.\n[08:03:23:DEBUG] Setting sigterm handler.\n[08:03:23:DEBUG] Stdout flushed after test process.\n[08:03:23:DEBUG] Processing test results.\n[08:03:23:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0\n\n[08:03:23:DEBUG] Extracting test data.\n[08:03:23:DEBUG] Retrieving crash reports.\n[08:03:23:DEBUG] Retrieving derived data.\n[08:03:23:DEBUG] Making desktop screenshots.\n[08:03:23:DEBUG] Killing simulators.\n[08:03:28:DEBUG] Wiping simulator.\nDevice wiped.\n[08:03:31:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "c2bbb4fc0a0e9b56446bb4890b053b5e1fe958de", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43ce24b19348dc11", "server_versions": [ "4216-cf4a3cd" ], "started_ts": "2019-03-25T14:48:44.989595", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios-slimnav", "buildnumber:114", "data:6eb13c8d069f6cc49468e1a56c36ce9a8f9b1fb3", "device_type:iPhone 6s", "gerrit:https://chromium-review.googlesource.com/c/1537290/4", "ios_version:11.4", "master:tryserver.chromium.mac", "name:ios_chrome_integration_egtests", "os:Mac-10.13.6", "platform:simulator", "pool:Chrome", "priority:30", "service_account:ios-isolated-tester@chops-service-accounts.iam.gserviceaccount.com", "slavename:vm148-m9", "spec_name:chromium.try:ios-slimnav", "stepname:ios_chrome_integration_egtests (iPhone 6s iOS 11.4) on Mac-10.13.6", "swarming.pool.template:none", "swarming.pool.version:d172b3da50f79bdc0892909417b8871a46e09913", "test:ios_chrome_integration_egtests", "user:None" ], "task_id": "43ce24b19348dc10", "try_number": "1" } ] }