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": [ "vm1149-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": [ "4201-b04f7f8" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-4" ] } ], "bot_id": "vm1149-m4", "bot_version": "871bf8bbbd6b908d4b34ce9527c521964cfde78a5466163676e12d9e76bc4a14", "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-15T03:50:06.695222", "costs_usd": [ 0.20018952865751766 ], "created_ts": "2019-03-15T03:34:54.564691", "duration": 868.4090008735657, "modified_ts": "2019-03-15T03:50:06.695222", "name": "ios_chrome_integration_egtests (iPhone 5s iOS 12.1)/Mac-10.13.6/85f103a491/ios12-beta-simulator/3770", "output": "[I 2019-03-14 20:35:15] About to install Xcode 10o45e in Xcode.app for ios\n[08:35:17:INFO] Using Xcode version 10.1 build 10O45e at /b/s/w/ir/Xcode.app/Contents/Developer\nDevice wiped.\n[08:35:22:DEBUG] Setting sigterm handler.\n[08:35:24:INFO] User defaults from command line:\n[08:35:24:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/95A0464C-5B94-4A1E-8868-E07E6E596F99\n[08:35:24:INFO] \n[08:35:24:INFO] Testing started on 'iPhone 5s'\n[08:35:25:INFO] 2019-03-14 20:35:25.032 xcodebuild[677:7030] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[08:35:25:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-aoazhbruxihdmlhifzsfvvgedica/Logs/Test/Test-Transient Testing-2019.03.14_20-35-24--0700.xcresult/1_Test/Diagnostics/ios_chrome_integration_egtests_module-D0D6617C-8F5C-48A7-97A4-744C016FEA4C/ios_chrome_integration_egtests_module-B1020BB0-178A-40BE-A972-1F427707504B/Session-ios_chrome_integration_egtests_module-2019-03-14_203525-upXKuk.log\n[08:35:25:INFO] 2019-03-14 20:35:25.033 xcodebuild[677:7021] [MT] IDETestOperationsObserverDebug: (976F4D77-EAC0-4989-A8F8-D0B755A1CA80) Beginning test session ios_chrome_integration_egtests_module-976F4D77-EAC0-4989-A8F8-D0B755A1CA80 at 2019-03-14 20:35:25.033 with Xcode 10O45e on target <DVTiPhoneSimulator: 0x7ffbaeb58d90> {\n[08:35:25:INFO] \t\tSimDevice: iPhone 5s (758AD4D0-24AC-4982-A8F1-6652AA554860, iOS 12.1, Shutdown)\n[08:35:25:INFO] } (12.1 (16B5084a))\n[08:35:42:INFO] 2019-03-14 20:35:42.823 ios_chrome_integration_egtests[806:8273] Crash handler setup started.\n[08:35:42:INFO] 2019-03-14 20:35:42.823 ios_chrome_integration_egtests[806:8273] Crash handler setup completed.\n[08:35:42:INFO] 2019-03-14 20:35:42.824 ios_chrome_integration_egtests[806:8273] Enabling accessibility for automation on Simulator.\n[08:35:45:INFO] 2019-03-14 20:35:45.645 ios_chrome_integration_egtests[806:8273] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7ff20885da00>.\n[08:35:45:INFO] [0314/203545.740477:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/758AD4D0-24AC-4982-A8F1-6652AA554860/data/Containers/Data/Application/93EF6233-5C25-4BCC-92A7-6B42AE064237/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/758AD4D0-24AC-4982-A8F1-6652AA554860/data/Containers/Data/Application/93EF6233-5C25-4BCC-92A7-6B42AE064237/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[08:35:52:INFO] Test Suite 'All tests' started at 2019-03-14 20:35:52.598\n[08:35:52:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-14 20:35:52.599\n[08:35:52:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-14 20:35:52.600\n[08:35:52:INFO] [0314/203552.629022:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:35:54:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[08:35:58:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (3.808 seconds).\n[08:35:58:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-14 20:35:58.317.\n[08:35:58:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 3.808 (5.717) seconds\n[08:35:58:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-14 20:35:58.318\n[08:35:58:INFO] [0314/203558.319778:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:35:58:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[08:36:00:INFO] [0314/203600.396561:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:06:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (7.892 seconds).\n[08:36:06:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[08:36:08:INFO] [0314/203608.164520:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:14:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (8.693 seconds).\n[08:36:14:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[08:36:17:INFO] [0314/203617.150836:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:25:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.108 seconds).\n[08:36:25:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[08:36:26:INFO] [0314/203626.902006:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:33:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.145 seconds).\n[08:36:33:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[08:36:35:INFO] [0314/203635.043836:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:42:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (9.426 seconds).\n[08:36:42:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[08:36:44:INFO] [0314/203644.543084:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:48:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (5.981 seconds).\n[08:36:48:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[08:36:50:INFO] [0314/203650.545550:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:36:59:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (10.908 seconds).\n[08:36:59:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[08:37:01:INFO] [0314/203701.354893:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:37:07:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.113 seconds).\n[08:37:07:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[08:37:09:INFO] [0314/203709.554974:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:37:17:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (9.769 seconds).\n[08:37:17:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-14 20:37:17.372.\n[08:37:17:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 79.036 (79.054) seconds\n[08:37:17:INFO] Test Suite 'CookiesTestCase' started at 2019-03-14 20:37:17.373\n[08:37:17:INFO] [0314/203717.375222:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:37:17:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[08:37:19:INFO] [0314/203719.495830:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:37:34:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.576 seconds).\n[08:37:34:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[08:37:49:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.171 seconds).\n[08:37:49:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[08:37:57:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.328 seconds).\n[08:37:57:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[08:38:10:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.035 seconds).\n[08:38:10:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[08:38:24:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (13.853 seconds).\n[08:38:24:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-14 20:38:24.347.\n[08:38:24:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 66.965 (66.974) seconds\n[08:38:24:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-14 20:38:24.348\n[08:38:24:INFO] [0314/203824.349441:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:38:24:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[08:38:25:INFO] 2019-03-14 20:38:25.889 ios_chrome_integration_egtests[806:8273] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[08:38:27:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.430 seconds).\n[08:38:27:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[08:38:29:INFO] 2019-03-14 20:38:29.582 ios_chrome_integration_egtests[806:8273] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[08:38:31:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.665 seconds).\n[08:38:31:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-14 20:38:31.450.\n[08:38:31:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 7.095 (7.102) seconds\n[08:38:31:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-14 20:38:31.451\n[08:38:31:INFO] [0314/203831.452081:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:38:31:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[08:38:41:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.547 seconds).\n[08:38:41:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[08:38:50:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (9.267 seconds).\n[08:38:50:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[08:38:56:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.802 seconds).\n[08:38:56:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[08:38:59:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.744 seconds).\n[08:38:59:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[08:39:09:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.369 seconds).\n[08:39:09:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-14 20:39:09.191.\n[08:39:09:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 37.730 (37.740) seconds\n[08:39:09:INFO] Test Suite 'FormInputTestCase' started at 2019-03-14 20:39:09.192\n[08:39:09:INFO] [0314/203909.193454:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:39:09:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[08:39:13:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.806 seconds).\n[08:39:13:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[08:39:15:INFO] [0314/203915.007347:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:39:16:INFO] 2019-03-14 20:39:16.613 ios_chrome_integration_egtests[806:8273] API error: <_UIKBCompatInputView: 0x7ff20e322f10; frame = (0 0; 0 0); layer = <CALayer: 0x6000032549e0>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[08:39:16:INFO] 2019-03-14 20:39:16.613 ios_chrome_integration_egtests[806:8273] API error: <_UIKBCompatInputView: 0x7ff20e322f10; frame = (0 0; 0 0); layer = <CALayer: 0x6000032549e0>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[08:39:21:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (8.207 seconds).\n[08:39:21:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-14 20:39:21.211.\n[08:39:21:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 12.013 (12.020) seconds\n[08:39:21:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-14 20:39:21.212\n[08:39:21:INFO] [0314/203921.213707:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:39:21:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[08:39:23:INFO] [0314/203923.114005:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:39:31:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (10.528 seconds).\n[08:39:31:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[08:39:35:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.672 seconds).\n[08:39:35:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[08:39:45:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.105 seconds).\n[08:39:45:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[08:39:50:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.775 seconds).\n[08:39:50:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[08:39:57:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.596 seconds).\n[08:39:57:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[08:40:03:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.091 seconds).\n[08:40:03:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-14 20:40:03.989.\n[08:40:03:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 42.768 (42.777) seconds\n[08:40:03:INFO] Test Suite 'NTPTilesTest' started at 2019-03-14 20:40:03.990\n[08:40:03:INFO] [0314/204003.990965:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:40:03:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[08:40:07:INFO] [0314/204007.765646:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:40:13:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.677 seconds).\n[08:40:13:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[08:40:38:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (24.460 seconds).\n[08:40:38:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-14 20:40:38.134.\n[08:40:38:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 34.137 (34.145) seconds\n[08:40:38:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-14 20:40:38.135\n[08:40:38:INFO] [0314/204038.137102:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:40:38:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[08:40:40:INFO] [0314/204040.167070:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:40:47:INFO] 2019-03-14 20:40:47.116 ios_chrome_integration_egtests[806:8273] Attempting to type key 1.\n[08:40:47:INFO] 2019-03-14 20:40:47.219 ios_chrome_integration_egtests[806:8273] Tapping on key: more, numbers.\n[08:40:47:INFO] 2019-03-14 20:40:47.304 ios_chrome_integration_egtests[806:8273] Tapping on key: 1.\n[08:40:47:INFO] 2019-03-14 20:40:47.438 ios_chrome_integration_egtests[806:8273] Attempting to type key 2.\n[08:40:47:INFO] 2019-03-14 20:40:47.438 ios_chrome_integration_egtests[806:8273] Tapping on key: 2.\n[08:40:47:INFO] 2019-03-14 20:40:47.507 ios_chrome_integration_egtests[806:8273] Attempting to type key 7.\n[08:40:47:INFO] 2019-03-14 20:40:47.508 ios_chrome_integration_egtests[806:8273] Tapping on key: 7.\n[08:40:47:INFO] [0314/204047.554792:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 3\n[08:40:47:INFO] 2019-03-14 20:40:47.586 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:47:INFO] 2019-03-14 20:40:47.588 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:47:INFO] [0314/204047.637412:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 5\n[08:40:47:INFO] 2019-03-14 20:40:47.662 ios_chrome_integration_egtests[806:8273] Attempting to type key 0.\n[08:40:47:INFO] 2019-03-14 20:40:47.664 ios_chrome_integration_egtests[806:8273] Tapping on key: 0.\n[08:40:47:INFO] 2019-03-14 20:40:47.745 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:47:INFO] 2019-03-14 20:40:47.748 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:47:INFO] 2019-03-14 20:40:47.821 ios_chrome_integration_egtests[806:8273] Attempting to type key 0.\n[08:40:47:INFO] 2019-03-14 20:40:47.823 ios_chrome_integration_egtests[806:8273] Tapping on key: 0.\n[08:40:47:INFO] [0314/204047.872745:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 9\n[08:40:47:INFO] 2019-03-14 20:40:47.894 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:47:INFO] 2019-03-14 20:40:47.897 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:47:INFO] 2019-03-14 20:40:47.980 ios_chrome_integration_egtests[806:8273] Attempting to type key 1.\n[08:40:47:INFO] 2019-03-14 20:40:47.981 ios_chrome_integration_egtests[806:8273] Tapping on key: 1.\n[08:40:48:INFO] 2019-03-14 20:40:48.065 ios_chrome_integration_egtests[806:8273] Attempting to type key :.\n[08:40:48:INFO] 2019-03-14 20:40:48.067 ios_chrome_integration_egtests[806:8273] Tapping on key: :.\n[08:40:48:INFO] 2019-03-14 20:40:48.146 ios_chrome_integration_egtests[806:8273] Attempting to type key 4.\n[08:40:48:INFO] 2019-03-14 20:40:48.147 ios_chrome_integration_egtests[806:8273] Tapping on key: 4.\n[08:40:48:INFO] [0314/204048.204222:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 15\n[08:40:48:INFO] 2019-03-14 20:40:48.236 ios_chrome_integration_egtests[806:8273] Attempting to type key 9.\n[08:40:48:INFO] 2019-03-14 20:40:48.237 ios_chrome_integration_egtests[806:8273] Tapping on key: 9.\n[08:40:48:INFO] 2019-03-14 20:40:48.315 ios_chrome_integration_egtests[806:8273] Attempting to type key 4.\n[08:40:48:INFO] 2019-03-14 20:40:48.316 ios_chrome_integration_egtests[806:8273] Tapping on key: 4.\n[08:40:48:INFO] 2019-03-14 20:40:48.390 ios_chrome_integration_egtests[806:8273] Attempting to type key 0.\n[08:40:48:INFO] 2019-03-14 20:40:48.391 ios_chrome_integration_egtests[806:8273] Tapping on key: 0.\n[08:40:48:INFO] 2019-03-14 20:40:48.469 ios_chrome_integration_egtests[806:8273] Attempting to type key 2.\n[08:40:48:INFO] 2019-03-14 20:40:48.469 ios_chrome_integration_egtests[806:8273] Tapping on key: 2.\n[08:40:48:INFO] 2019-03-14 20:40:48.543 ios_chrome_integration_egtests[806:8273] Attempting to type key /.\n[08:40:48:INFO] 2019-03-14 20:40:48.545 ios_chrome_integration_egtests[806:8273] Tapping on key: /.\n[08:40:48:INFO] 2019-03-14 20:40:48.614 ios_chrome_integration_egtests[806:8273] Attempting to type key t.\n[08:40:48:INFO] 2019-03-14 20:40:48.923 ios_chrome_integration_egtests[806:8273] Tapping on key: more, letters.\n[08:40:48:INFO] 2019-03-14 20:40:48.975 ios_chrome_integration_egtests[806:8273] Tapping on key: t.\n[08:40:49:INFO] 2019-03-14 20:40:49.047 ios_chrome_integration_egtests[806:8273] Attempting to type key e.\n[08:40:49:INFO] 2019-03-14 20:40:49.047 ios_chrome_integration_egtests[806:8273] Tapping on key: e.\n[08:40:49:INFO] 2019-03-14 20:40:49.117 ios_chrome_integration_egtests[806:8273] Attempting to type key s.\n[08:40:49:INFO] 2019-03-14 20:40:49.118 ios_chrome_integration_egtests[806:8273] Tapping on key: s.\n[08:40:49:INFO] 2019-03-14 20:40:49.183 ios_chrome_integration_egtests[806:8273] Attempting to type key t.\n[08:40:49:INFO] 2019-03-14 20:40:49.184 ios_chrome_integration_egtests[806:8273] Tapping on key: t.\n[08:40:49:INFO] 2019-03-14 20:40:49.262 ios_chrome_integration_egtests[806:8273] Attempting to type key -.\n[08:40:49:INFO] 2019-03-14 20:40:49.365 ios_chrome_integration_egtests[806:8273] Tapping on key: more, numbers.\n[08:40:49:INFO] 2019-03-14 20:40:49.437 ios_chrome_integration_egtests[806:8273] Tapping on key: -.\n[08:40:49:INFO] 2019-03-14 20:40:49.520 ios_chrome_integration_egtests[806:8273] Attempting to type key p.\n[08:40:49:INFO] 2019-03-14 20:40:49.829 ios_chrome_integration_egtests[806:8273] Tapping on key: more, letters.\n[08:40:49:INFO] 2019-03-14 20:40:49.882 ios_chrome_integration_egtests[806:8273] Tapping on key: p.\n[08:40:49:INFO] 2019-03-14 20:40:49.963 ios_chrome_integration_egtests[806:8273] Attempting to type key a.\n[08:40:49:INFO] 2019-03-14 20:40:49.964 ios_chrome_integration_egtests[806:8273] Tapping on key: a.\n[08:40:50:INFO] 2019-03-14 20:40:50.032 ios_chrome_integration_egtests[806:8273] Attempting to type key g.\n[08:40:50:INFO] 2019-03-14 20:40:50.034 ios_chrome_integration_egtests[806:8273] Tapping on key: g.\n[08:40:50:INFO] 2019-03-14 20:40:50.113 ios_chrome_integration_egtests[806:8273] Attempting to type key e.\n[08:40:50:INFO] 2019-03-14 20:40:50.114 ios_chrome_integration_egtests[806:8273] Tapping on key: e.\n[08:40:50:INFO] 2019-03-14 20:40:50.184 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:50:INFO] 2019-03-14 20:40:50.187 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:50:INFO] 2019-03-14 20:40:50.261 ios_chrome_integration_egtests[806:8273] Attempting to type key h.\n[08:40:50:INFO] 2019-03-14 20:40:50.262 ios_chrome_integration_egtests[806:8273] Tapping on key: h.\n[08:40:50:INFO] 2019-03-14 20:40:50.362 ios_chrome_integration_egtests[806:8273] Attempting to type key t.\n[08:40:50:INFO] 2019-03-14 20:40:50.364 ios_chrome_integration_egtests[806:8273] Tapping on key: t.\n[08:40:50:INFO] 2019-03-14 20:40:50.440 ios_chrome_integration_egtests[806:8273] Attempting to type key m.\n[08:40:50:INFO] 2019-03-14 20:40:50.441 ios_chrome_integration_egtests[806:8273] Tapping on key: m.\n[08:40:50:INFO] 2019-03-14 20:40:50.521 ios_chrome_integration_egtests[806:8273] Attempting to type key l.\n[08:40:50:INFO] 2019-03-14 20:40:50.522 ios_chrome_integration_egtests[806:8273] Tapping on key: l.\n[08:40:50:INFO] 2019-03-14 20:40:50.592 ios_chrome_integration_egtests[806:8273] Attempting to type key\n[08:40:50:INFO] .\n[08:40:50:INFO] 2019-03-14 20:40:50.594 ios_chrome_integration_egtests[806:8273] Tapping on key: Go.\n[08:40:56:INFO] 2019-03-14 20:40:56.679 ios_chrome_integration_egtests[806:8273] Attempting to type key 1.\n[08:40:56:INFO] 2019-03-14 20:40:56.782 ios_chrome_integration_egtests[806:8273] Tapping on key: more, numbers.\n[08:40:56:INFO] 2019-03-14 20:40:56.852 ios_chrome_integration_egtests[806:8273] Tapping on key: 1.\n[08:40:56:INFO] 2019-03-14 20:40:56.961 ios_chrome_integration_egtests[806:8273] Attempting to type key 2.\n[08:40:56:INFO] 2019-03-14 20:40:56.962 ios_chrome_integration_egtests[806:8273] Tapping on key: 2.\n[08:40:57:INFO] 2019-03-14 20:40:57.041 ios_chrome_integration_egtests[806:8273] Attempting to type key 7.\n[08:40:57:INFO] 2019-03-14 20:40:57.042 ios_chrome_integration_egtests[806:8273] Tapping on key: 7.\n[08:40:57:INFO] 2019-03-14 20:40:57.127 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:57:INFO] 2019-03-14 20:40:57.129 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:57:INFO] 2019-03-14 20:40:57.212 ios_chrome_integration_egtests[806:8273] Attempting to type key 0.\n[08:40:57:INFO] 2019-03-14 20:40:57.214 ios_chrome_integration_egtests[806:8273] Tapping on key: 0.\n[08:40:57:INFO] [0314/204057.267935:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 23\n[08:40:57:INFO] 2019-03-14 20:40:57.299 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:57:INFO] 2019-03-14 20:40:57.300 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:57:INFO] 2019-03-14 20:40:57.387 ios_chrome_integration_egtests[806:8273] Attempting to type key 0.\n[08:40:57:INFO] 2019-03-14 20:40:57.388 ios_chrome_integration_egtests[806:8273] Tapping on key: 0.\n[08:40:57:INFO] 2019-03-14 20:40:57.470 ios_chrome_integration_egtests[806:8273] Attempting to type key ..\n[08:40:57:INFO] 2019-03-14 20:40:57.472 ios_chrome_integration_egtests[806:8273] Tapping on key: ..\n[08:40:57:INFO] 2019-03-14 20:40:57.568 ios_chrome_integration_egtests[806:8273] Attempting to type key 1.\n[08:40:57:INFO] 2019-03-14 20:40:57.571 ios_chrome_integration_egtests[806:8273] Tapping on key: 1.\n[08:40:57:INFO] 2019-03-14 20:40:57.648 ios_chrome_integration_egtests[806:8273] Attempting to type key :.\n[08:40:57:INFO] 2019-03-14 20:40:57.649 ios_chrome_integration_egtests[806:8273] Tapping on key: :.\n[08:40:57:INFO] 2019-03-14 20:40:57.734 ios_chrome_integration_egtests[806:8273] Attempting to type key 4.\n[08:40:57:INFO] 2019-03-14 20:40:57.735 ios_chrome_integration_egtests[806:8273] Tapping on key: 4.\n[08:40:57:INFO] 2019-03-14 20:40:57.824 ios_chrome_integration_egtests[806:8273] Attempting to type key 9.\n[08:40:57:INFO] 2019-03-14 20:40:57.825 ios_chrome_integration_egtests[806:8273] Tapping on key: 9.\n[08:40:57:INFO] 2019-03-14 20:40:57.921 ios_chrome_integration_egtests[806:8273] Attempting to type key 4.\n[08:40:57:INFO] 2019-03-14 20:40:57.923 ios_chrome_integration_egtests[806:8273] Tapping on key: 4.\n[08:40:58:INFO] 2019-03-14 20:40:58.004 ios_chrome_integration_egtests[806:8273] Attempting to type key 0.\n[08:40:58:INFO] 2019-03-14 20:40:58.006 ios_chrome_integration_egtests[806:8273] Tapping on key: 0.\n[08:40:58:INFO] 2019-03-14 20:40:58.095 ios_chrome_integration_egtests[806:8273] Attempting to type key 2.\n[08:40:58:INFO] 2019-03-14 20:40:58.095 ios_chrome_integration_egtests[806:8273] Tapping on key: 2.\n[08:40:58:INFO] 2019-03-14 20:40:58.178 ios_chrome_integration_egtests[806:8273] Attempting to type key /.\n[08:40:58:INFO] 2019-03-14 20:40:58.179 ios_chrome_integration_egtests[806:8273] Tapping on key: /.\n[08:40:58:INFO] 2019-03-14 20:40:58.262 ios_chrome_integration_egtests[806:8273] Attempting to type key t.\n[08:40:58:INFO] 2019-03-14 20:40:58.571 ios_chrome_integration_egtests[806:8273] Tapping on key: more, letters.\n[08:40:58:INFO] 2019-03-14 20:40:58.623 ios_chrome_integration_egtests[806:8273] Tapping on key: t.\n[08:40:58:INFO] 2019-03-14 20:40:58.692 ios_chrome_integration_egtests[806:8273] Attempting to type key e.\n[08:40:58:INFO] 2019-03-14 20:40:58.693 ios_chrome_integration_egtests[806:8273] Tapping on key: e.\n[08:40:58:INFO] 2019-03-14 20:40:58.764 ios_chrome_integration_egtests[806:8273] Attempting to type key s.\n[08:40:58:INFO] 2019-03-14 20:40:58.765 ios_chrome_integration_egtests[806:8273] Tapping on key: s.\n[08:40:58:INFO] 2019-03-14 20:40:58.847 ios_chrome_integration_egtests[806:8273] Attempting to type key t.\n[08:40:58:INFO] 2019-03-14 20:40:58.848 ios_chrome_integration_egtests[806:8273] Tapping on key: t.\n[08:40:58:INFO] 2019-03-14 20:40:58.918 ios_chrome_integration_egtests[806:8273] Attempting to type key -.\n[08:40:59:INFO] 2019-03-14 20:40:59.022 ios_chrome_integration_egtests[806:8273] Tapping on key: more, numbers.\n[08:40:59:INFO] 2019-03-14 20:40:59.078 ios_chrome_integration_egtests[806:8273] Tapping on key: -.\n[08:40:59:INFO] 2019-03-14 20:40:59.164 ios_chrome_integration_egtests[806:8273] Attempting to type key p.\n[08:40:59:INFO] 2019-03-14 20:40:59.472 ios_chrome_integration_egtests[806:8273] Tapping on key: more, letters.\n[08:40:59:INFO] 2019-03-14 20:40:59.528 ios_chrome_integration_egtests[806:8273] Tapping on key: p.\n[08:40:59:INFO] 2019-03-14 20:40:59.596 ios_chrome_integration_egtests[806:8273] Attempting to type key a.\n[08:40:59:INFO] 2019-03-14 20:40:59.597 ios_chrome_integration_egtests[806:8273] Tapping on key: a.\n[08:40:59:INFO] 2019-03-14 20:40:59.670 ios_chrome_integration_egtests[806:8273] Attempting to type key g.\n[08:40:59:INFO] 2019-03-14 20:40:59.671 ios_chrome_integration_egtests[806:8273] Tapping on key: g.\n[08:41:04:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (25.878 seconds).\n[08:41:04:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-14 20:41:04.024.\n[08:41:04:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 25.878 (25.889) seconds\n[08:41:04:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-14 20:41:04.025\n[08:41:04:INFO] [0314/204104.027271:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:41:04:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[08:41:07:INFO] [0314/204107.694956:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:41:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (18.127 seconds).\n[08:41:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[08:41:52:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (30.619 seconds).\n[08:41:52:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[08:42:09:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.019 seconds).\n[08:42:09:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[08:42:31:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (21.459 seconds).\n[08:42:31:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[08:42:46:INFO] [0314/204246.477469:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:42:46:INFO] [0314/204246.488872:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:42:46:INFO] [0314/204246.499111:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:42:46:INFO] [0314/204246.508816:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:42:49:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (18.387 seconds).\n[08:42:49:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[08:42:58:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (8.370 seconds).\n[08:42:58:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[08:43:05:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.349 seconds).\n[08:43:05:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[08:43:15:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (10.030 seconds).\n[08:43:15:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[08:43:33:INFO] [0314/204333.309224:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:43:47:INFO] [0314/204347.401138:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:43:47:INFO] [0314/204347.418736:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:43:47:INFO] [0314/204347.424126:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:43:47:INFO] [0314/204347.437153:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:43:50:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (35.208 seconds).\n[08:43:50:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[08:44:04:INFO] [0314/204404.995844:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:44:05:INFO] [0314/204405.019832:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:44:05:INFO] [0314/204405.028850:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:44:05:INFO] [0314/204405.038836:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[08:44:08:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (17.537 seconds).\n[08:44:08:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[08:44:23:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.093 seconds).\n[08:44:23:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-14 20:44:23.237.\n[08:44:23:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 199.198 (199.212) seconds\n[08:44:23:INFO] Test Suite 'UKMTestCase' started at 2019-03-14 20:44:23.238\n[08:44:23:INFO] [0314/204423.240650:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:44:23:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[08:44:30:INFO] 2019-03-14 20:44:30.500 ios_chrome_integration_egtests[806:8273] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7ff207e2eb90; baseClass = UIButton; frame = (16 516; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x600001dcf280>>, Touch Target: {86, 36}\n[08:44:31:INFO] [0314/204431.894487:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:44:31:INFO] [0314/204431.907771:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:44:31:INFO] [0314/204431.907941:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:44:31:INFO] [0314/204431.932517:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:44:32:INFO] [0314/204432.915001:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:44:44:INFO] [0314/204444.177626:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:44:54:INFO] [0314/204454.527602:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:44:54:INFO] [0314/204454.527771:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:44:55:INFO] [0314/204455.543756:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:44:55:INFO] [0314/204455.641183:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[08:45:06:INFO] [0314/204506.836147:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:45:10:INFO] [0314/204510.698285:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:45:12:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (49.375 seconds).\n[08:45:12:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[08:45:21:INFO] [0314/204521.182978:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:45:21:INFO] [0314/204521.185251:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:45:21:INFO] [0314/204521.210351:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:45:21:INFO] [0314/204521.210489:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:45:22:INFO] [0314/204522.230683:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:45:39:INFO] [0314/204539.299594:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:45:43:INFO] [0314/204543.178996:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:45:45:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (32.474 seconds).\n[08:45:45:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[08:45:53:INFO] [0314/204553.683677:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:45:53:INFO] [0314/204553.686016:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:45:53:INFO] [0314/204553.706575:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:45:53:INFO] [0314/204553.706712:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:45:54:INFO] [0314/204554.732523:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:46:06:INFO] [0314/204606.001939:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:46:09:INFO] [0314/204609.864887:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:46:11:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (26.684 seconds).\n[08:46:11:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[08:46:20:INFO] [0314/204620.396795:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:46:20:INFO] [0314/204620.399108:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:46:20:INFO] [0314/204620.424285:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:46:20:INFO] [0314/204620.424434:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:46:21:INFO] [0314/204621.467148:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:46:41:INFO] [0314/204641.818926:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:46:45:INFO] [0314/204645.629925:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:46:47:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (35.784 seconds).\n[08:46:47:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[08:46:55:INFO] [0314/204655.953104:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:46:55:INFO] [0314/204655.954608:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:46:55:INFO] [0314/204655.976235:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:46:55:INFO] [0314/204655.976359:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:46:57:INFO] [0314/204657.002444:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:47:28:INFO] [0314/204728.903024:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:47:32:INFO] [0314/204732.729104:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:47:39:INFO] [0314/204739.077223:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:47:39:INFO] [0314/204739.079602:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:47:39:INFO] [0314/204739.104856:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:47:39:INFO] [0314/204739.105021:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:47:40:INFO] [0314/204740.124079:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:47:51:INFO] [0314/204751.207097:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:47:55:INFO] [0314/204755.045125:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:47:56:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (69.366 seconds).\n[08:47:56:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[08:48:05:INFO] [0314/204805.421622:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:48:05:INFO] [0314/204805.423095:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:48:05:INFO] [0314/204805.445801:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:48:05:INFO] [0314/204805.445920:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:48:06:INFO] [0314/204806.458340:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:48:19:INFO] [0314/204819.991477:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:48:21:INFO] [0314/204821.382610:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:48:22:INFO] [0314/204822.782796:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:48:24:INFO] [0314/204824.173470:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:48:33:INFO] [0314/204833.799041:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:48:37:INFO] [0314/204837.661515:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:48:39:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (42.610 seconds).\n[08:48:39:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[08:48:47:INFO] [0314/204847.972789:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:48:47:INFO] [0314/204847.974265:WARNING:sync_encryption_handler_impl.cc(1079)] Nigori had empty encryption keybag.\n[08:48:47:INFO] [0314/204847.991583:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:48:47:INFO] [0314/204847.991722:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:48:49:INFO] [0314/204849.013885:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:49:00:INFO] [0314/204900.064913:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:49:10:INFO] [0314/204910.282078:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:49:10:INFO] [0314/204910.282200:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:49:11:INFO] [0314/204911.275159:INFO:profile_sync_service.cc(1780)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[08:49:11:INFO] [0314/204911.348360:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[08:49:22:INFO] [0314/204922.320095:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:49:26:INFO] [0314/204926.144466:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[08:49:28:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (48.490 seconds).\n[08:49:28:INFO] Test Suite 'UKMTestCase' passed at 2019-03-14 20:49:28.032.\n[08:49:28:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 304.783 (304.794) seconds\n[08:49:28:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-14 20:49:28.033.\n[08:49:28:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 813.410 (815.434) seconds\n[08:49:28:INFO] Test Suite 'All tests' passed at 2019-03-14 20:49:28.035.\n[08:49:28:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 813.410 (815.437) seconds\n[08:49:28:INFO] \n[08:49:28:INFO] \n[08:49:28:INFO] Test session results and logs:\n[08:49:28:INFO] \t/Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-aoazhbruxihdmlhifzsfvvgedica/Logs/Test/Test-Transient Testing-2019.03.14_20-35-24--0700.xcresult\n[08:49:28:INFO] \n[08:49:28:INFO] 2019-03-14 20:49:28.359 xcodebuild[677:7021] [MT] IDETestOperationsObserverDebug: 843.535 elapsed -- Testing started completed.\n[08:49:28:INFO] 2019-03-14 20:49:28.360 xcodebuild[677:7021] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[08:49:28:INFO] 2019-03-14 20:49:28.360 xcodebuild[677:7021] [MT] IDETestOperationsObserverDebug: 843.535 sec, +843.535 sec -- end\n[08:49:28:INFO] ** TEST EXECUTE SUCCEEDED **\n[08:49:28:INFO] \n[08:49:34:DEBUG] Finished print_process_output.\n[08:49:34:INFO] Waiting for test process to terminate.\n[08:49:34:INFO] Test process terminated.\n[08:49:34:DEBUG] Setting sigterm handler.\n[08:49:34:DEBUG] Stdout flushed after test process.\n[08:49:34:DEBUG] Processing test results.\n[08:49:34:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0\n\n[08:49:34:DEBUG] Extracting test data.\n[08:49:34:DEBUG] Retrieving crash reports.\n[08:49:34:DEBUG] Retrieving derived data.\n[08:49:34:DEBUG] Making desktop screenshots.\n[08:49:34:DEBUG] Killing simulators.\n[08:49:39:DEBUG] Wiping simulator.\nDevice wiped.\n[08:49:42:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "a6d9c01a11dab233bcbc5a3ec7b58310f2f261ba", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43983c4179e5ce11", "server_versions": [ "4201-b04f7f8" ], "started_ts": "2019-03-15T03:34:54.878494", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-beta-simulator", "buildnumber:3770", "data:85f103a49102aaba1cce2b2f7832c0520c5657f0", "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:495795e5448a25dcbe03864a2faf331aff3ae0ae", "test:ios_chrome_integration_egtests", "user:None" ], "task_id": "43983c4179e5ce10", "try_number": "1" } ] }