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_10o45e" ] }, { "key": "cores", "value": [ "4" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-i7-4578U" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "8086", "8086:0a2e" ] }, { "key": "hidpi", "value": [ "0" ] }, { "key": "id", "value": [ "build405-h9" ] }, { "key": "mac_model", "value": [ "Macmini7,1" ] }, { "key": "machine_type", "value": [ "n1-standard-4" ] }, { "key": "os", "value": [ "Mac", "Mac-10.14", "Mac-10.14.4" ] }, { "key": "pool", "value": [ "Chrome" ] }, { "key": "python", "value": [ "2.7.10" ] }, { "key": "server_version", "value": [ "4303-bae210e" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-iad", "us-iad-golo", "us-iad-golo-h9" ] } ], "bot_id": "build405-h9", "bot_version": "b2472a221f42ebd49c08d623b88315f958b0b6bcd656bb6734cb2e4dc5e3ea1b", "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": "QDLPZ3UAgZNoR5d69kckoahXHUl1oKiq5UVzhbr3X4EC" }, { "package_name": "infra/tools/luci/vpython/mac-amd64", "path": ".swarming_module", "version": "up_SZsefNVjCYV99Mh2FddLgkRCR_FxXo_Qn2bhROIsC" } ] }, "completed_ts": "2019-05-24T16:30:42.843196", "costs_usd": [ 0.365645483255464 ], "created_ts": "2019-05-24T15:45:39.754592", "duration": 2647.1426651477814, "modified_ts": "2019-05-24T16:30:42.843196", "name": "ios_chrome_unified_consent_egtests (iPhone X iOS 11.4) (with patch)/Mac-10.13.6/8597aa03e3/ios-simulator-full-configs/14972", "output": "[I 2019-05-24 08:46:00] About to install Xcode 10b61 in Xcode.app for ios\n[08:46:01:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\n[08:46:01:INFO] Killing simulators.\nDevice wiped.\n[08:46:10:DEBUG] Setting sigterm handler.\n[08:46:11:INFO] User defaults from command line:\n[08:46:11:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/ECBF67E4-464A-4692-84BE-A4B068FD5644\n[08:46:11:INFO] \n[08:46:11:INFO] Testing started on 'iPhone X'\n[08:46:11:INFO] 2019-05-24 08:46:11.893 xcodebuild[38680:910681] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[08:46:11:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-bxwrbforqytmxdftuexcbqnxtaiw/Logs/Test/Test-Transient Testing-2019.05.24_08-46-11--0700.xcresult/1_Test/Diagnostics/ios_chrome_unified_consent_egtests_module-67E12DE5-4234-4123-ADAD-4DF463720557/ios_chrome_unified_consent_egtests_module-1CA0740A-0060-4E47-93BA-28873091DEE3/Session-ios_chrome_unified_consent_egtests_module-2019-05-24_084611-eg5Ozt.log\n[08:46:11:INFO] 2019-05-24 08:46:11.893 xcodebuild[38680:910663] [MT] IDETestOperationsObserverDebug: (3F33D14F-0AFD-46EB-A529-BD137FE09B6E) Beginning test session ios_chrome_unified_consent_egtests_module-3F33D14F-0AFD-46EB-A529-BD137FE09B6E at 2019-05-24 08:46:11.893 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fe3780a6e50> {\n[08:46:11:INFO] \t\tSimDevice: iPhone X (6FAF9315-97B7-4BFB-8AE8-02FF43D18C44, iOS 11.4, Shutdown)\n[08:46:11:INFO] } (11.4 (15F79))\n[08:46:39:INFO] 2019-05-24 08:46:39.373 ios_chrome_unified_consent_egtests[38803:911951] Crash handler setup started.\n[08:46:39:INFO] 2019-05-24 08:46:39.374 ios_chrome_unified_consent_egtests[38803:911951] Crash handler setup completed.\n[08:46:39:INFO] 2019-05-24 08:46:39.374 ios_chrome_unified_consent_egtests[38803:911951] Enabling accessibility for automation on Simulator.\n[08:46:39:INFO] objc[38803]: 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 (0x14d5414d0) 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 (0x14d357e38). One of the two will be used. Which one is undefined.\n[08:46:42:INFO] 2019-05-24 08:46:42.126 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:46:42:INFO] 2019-05-24 08:46:42.126 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:46:42:INFO] 2019-05-24 08:46:42.126 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:46:42:INFO] 2019-05-24 08:46:42.127 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:46:43:INFO] 2019-05-24 08:46:43.532 ios_chrome_unified_consent_egtests[38803:911951] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f890597d000>.\n[08:46:44:INFO] [0524/084644.231520:ERROR:send_tab_to_self_client_service_ios.mm(120)] Not implemented reached in virtual void send_tab_to_self::SendTabToSelfClientServiceIOS::EntriesRemovedRemotely(const std::vector<std::string> &)\n[08:46:44:INFO] [0524/084644.579661:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/6FAF9315-97B7-4BFB-8AE8-02FF43D18C44/data/Containers/Data/Application/EBEDA645-0174-4911-BBA1-43EAA3A8D1F4/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/6FAF9315-97B7-4BFB-8AE8-02FF43D18C44/data/Containers/Data/Application/EBEDA645-0174-4911-BBA1-43EAA3A8D1F4/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[08:46:50:INFO] Test Suite 'All tests' started at 2019-05-24 08:46:50.814\n[08:46:50:INFO] Test Suite 'ios_chrome_unified_consent_egtests.app' started at 2019-05-24 08:46:50.815\n[08:46:50:INFO] Test Suite 'AccountCollectionsTestCase' started at 2019-05-24 08:46:50.815\n[08:46:50:INFO] [0524/084650.823994:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:46:52:INFO] Test Case '-[AccountCollectionsTestCase testMDMError]' started.\n[08:46:54:INFO] 2019-05-24 08:46:54.654 ios_chrome_unified_consent_egtests[38803:911951] -- Earl Grey Test Disabled -- Sync section was moved to the Sync and Google services settings screen, so it is no longer present in the account settings screen. This test is now covered by GoogleServicesSettingsTestCase.\n[08:46:56:INFO] Test Case '-[AccountCollectionsTestCase testMDMError]' passed (4.255 seconds).\n[08:46:56:INFO] Test Case '-[AccountCollectionsTestCase testSignInDisconnectCancelled]' started.\n[08:47:07:INFO] 2019-05-24 08:47:07.155 ios_chrome_unified_consent_egtests[38803:911951] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7f8904fe8450; baseClass = UIButton; frame = (16 726; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x6040006ef080>>, Touch Target: {86, 36}\n[08:47:07:INFO] [0524/084707.773873:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[08:47:07:INFO] [0524/084707.817230:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[08:47:07:INFO] [0524/084707.834846:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:47:07:INFO] [0524/084707.835090:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:47:07:INFO] [0524/084707.929003:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:47:07:INFO] [0524/084707.929414:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:47:20:INFO] [0524/084720.323707:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:47:20:INFO] [0524/084720.357208:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:47:20:INFO] [0524/084720.365532:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:47:21:INFO] [0524/084721.356335:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:47:22:INFO] [0524/084722.439535:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:47:23:INFO] Test Case '-[AccountCollectionsTestCase testSignInDisconnectCancelled]' passed (26.720 seconds).\n[08:47:23:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnDisconnectAccount]' started.\n[08:47:25:INFO] [0524/084725.292300:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:47:33:INFO] [0524/084733.560223:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:47:34:INFO] [0524/084734.113255:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:47:34:INFO] [0524/084734.113772:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:47:34:INFO] [0524/084734.116369:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:47:34:INFO] [0524/084734.116632:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:47:34:INFO] [0524/084734.279635:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:47:45:INFO] [0524/084745.373519:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:47:45:INFO] [0524/084745.442669:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:47:45:INFO] [0524/084745.442862:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:47:50:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnDisconnectAccount]' passed (27.110 seconds).\n[08:47:50:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnSyncSettings]' started.\n[08:47:56:INFO] [0524/084756.135408:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:48:01:INFO] [0524/084801.150635:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:48:01:INFO] [0524/084801.151308:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:48:01:INFO] [0524/084801.209321:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:48:01:INFO] [0524/084801.209732:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:48:01:INFO] [0524/084801.349148:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:48:11:INFO] [0524/084811.589591:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:48:11:INFO] [0524/084811.656548:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:48:11:INFO] [0524/084811.656728:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:48:16:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnSyncSettings]' passed (26.196 seconds).\n[08:48:16:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadOnRemoveAccount]' started.\n[08:48:27:INFO] [0524/084827.418261:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:48:27:INFO] [0524/084827.418417:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:48:27:INFO] [0524/084827.420363:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:48:27:INFO] [0524/084827.420536:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:48:27:INFO] [0524/084827.448664:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:48:27:INFO] [0524/084827.448872:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[08:48:27:INFO] [0524/084827.512323:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:48:27:INFO] [0524/084827.512479:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[08:48:27:INFO] [0524/084827.564026:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:48:41:INFO] [0524/084840.981097:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:48:41:INFO] [0524/084841.035084:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:48:41:INFO] [0524/084841.035255:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:48:44:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadOnRemoveAccount]' passed (27.410 seconds).\n[08:48:44:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadSyncOnForgetIdentity]' started.\n[08:48:46:INFO] 2019-05-24 08:48:46.212 ios_chrome_unified_consent_egtests[38803:911951] -- Earl Grey Test Disabled -- Sync section was moved to the Sync and Google services settings screen, so it is no longer present in the account settings screen. This test is now covered by GoogleServicesSettingsTestCase.\n[08:48:48:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadSyncOnForgetIdentity]' passed (3.684 seconds).\n[08:48:48:INFO] Test Case '-[AccountCollectionsTestCase testSignOutOnRemoveAccount]' started.\n[08:48:58:INFO] [0524/084858.449115:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:48:58:INFO] [0524/084858.449414:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:48:58:INFO] [0524/084858.451486:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:48:58:INFO] [0524/084858.452553:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:48:58:INFO] [0524/084858.553476:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:49:09:INFO] [0524/084909.724873:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:49:09:INFO] [0524/084909.793452:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:49:09:INFO] [0524/084909.793641:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:49:14:INFO] [0524/084914.097473:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:49:15:INFO] Test Case '-[AccountCollectionsTestCase testSignOutOnRemoveAccount]' passed (27.609 seconds).\n[08:49:15:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithPassphraseRequired]' started.\n[08:49:17:INFO] 2019-05-24 08:49:17.691 ios_chrome_unified_consent_egtests[38803:911951] -- Earl Grey Test Disabled -- Sync section was moved to the Sync and Google services settings screen, so it is no longer present in the account settings screen. This test is now covered by GoogleServicesSettingsTestCase.\n[08:49:19:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithPassphraseRequired]' passed (3.871 seconds).\n[08:49:19:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithSyncingMessage]' started.\n[08:49:21:INFO] 2019-05-24 08:49:21.363 ios_chrome_unified_consent_egtests[38803:911951] -- Earl Grey Test Disabled -- Sync section was moved to the Sync and Google services settings screen, so it is no longer present in the account settings screen. This test is now covered by GoogleServicesSettingsTestCase.\n[08:49:23:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithSyncingMessage]' passed (3.672 seconds).\n[08:49:23:INFO] Test Suite 'AccountCollectionsTestCase' passed at 2019-05-24 08:49:23.218.\n[08:49:23:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 150.526 (152.404) seconds\n[08:49:23:INFO] Test Suite 'BlockPopupsTestCase' started at 2019-05-24 08:49:23.219\n[08:49:23:INFO] [0524/084923.220538:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:49:23:INFO] Test Case '-[BlockPopupsTestCase testAccessibilityOfBlockPopupSettings]' started.\n[08:49:41:INFO] Test Case '-[BlockPopupsTestCase testAccessibilityOfBlockPopupSettings]' passed (18.732 seconds).\n[08:49:41:INFO] Test Case '-[BlockPopupsTestCase testPopupsAllowed]' started.\n[08:49:44:INFO] [0524/084944.075300:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:49:48:INFO] Test Case '-[BlockPopupsTestCase testPopupsAllowed]' passed (6.159 seconds).\n[08:49:48:INFO] Test Case '-[BlockPopupsTestCase testPopupsBlocked]' started.\n[08:49:53:INFO] Test Case '-[BlockPopupsTestCase testPopupsBlocked]' passed (5.397 seconds).\n[08:49:53:INFO] Test Case '-[BlockPopupsTestCase testSettingsPageWithExceptions]' started.\n[08:50:15:INFO] Test Case '-[BlockPopupsTestCase testSettingsPageWithExceptions]' passed (22.296 seconds).\n[08:50:15:INFO] Test Suite 'BlockPopupsTestCase' passed at 2019-05-24 08:50:15.811.\n[08:50:15:INFO] \t Executed 4 tests, with 0 failures (0 unexpected) in 52.583 (52.591) seconds\n[08:50:15:INFO] Test Suite 'BookmarksAccessibilityTestCase' started at 2019-05-24 08:50:15.811\n[08:50:15:INFO] [0524/085015.812655:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:50:15:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' started.\n[08:50:30:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' passed (14.916 seconds).\n[08:50:30:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' started.\n[08:50:47:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' passed (17.084 seconds).\n[08:50:47:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' started.\n[08:51:04:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' passed (17.071 seconds).\n[08:51:04:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' started.\n[08:51:15:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' passed (10.155 seconds).\n[08:51:15:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' started.\n[08:51:32:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' passed (17.085 seconds).\n[08:51:32:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' started.\n[08:51:51:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' passed (19.790 seconds).\n[08:51:51:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' started.\n[08:52:04:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' passed (12.924 seconds).\n[08:52:04:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' started.\n[08:52:17:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' passed (12.410 seconds).\n[08:52:17:INFO] Test Suite 'BookmarksAccessibilityTestCase' passed at 2019-05-24 08:52:17.258.\n[08:52:17:INFO] \t Executed 8 tests, with 0 failures (0 unexpected) in 121.435 (121.447) seconds\n[08:52:17:INFO] Test Suite 'BookmarksEntriesTestCase' started at 2019-05-24 08:52:17.259\n[08:52:17:INFO] [0524/085217.260495:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:52:17:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' started.\n[08:52:32:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' passed (15.066 seconds).\n[08:52:32:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' started.\n[08:52:37:INFO] [0524/085237.716764:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:52:48:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' passed (15.723 seconds).\n[08:52:48:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' started.\n[08:53:03:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' passed (15.796 seconds).\n[08:53:03:INFO] Test Case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' started.\n[08:53:15:INFO] 2019-05-24 08:53:15.810 ios_chrome_unified_consent_egtests[38803:911951] Returning local object of class NSString\n[08:53:19:INFO] Test Case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' passed (15.838 seconds).\n[08:53:19:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' started.\n[08:53:36:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' passed (16.708 seconds).\n[08:53:36:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' started.\n[08:53:52:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' passed (16.299 seconds).\n[08:53:52:INFO] Test Case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' started.\n[08:54:22:INFO] Test Case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' passed (30.106 seconds).\n[08:54:22:INFO] Test Case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' started.\n[08:54:38:INFO] Test Case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' passed (15.462 seconds).\n[08:54:38:INFO] Test Case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' started.\n[08:54:58:INFO] Test Case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' passed (19.739 seconds).\n[08:54:58:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' started.\n[08:55:27:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' passed (29.333 seconds).\n[08:55:27:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' started.\n[08:55:52:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed (25.613 seconds).\n[08:55:52:INFO] Test Case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' started.\n[08:56:33:INFO] Test Case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' passed (40.292 seconds).\n[08:56:33:INFO] Test Case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' started.\n[08:56:43:INFO] [0524/085643.312139:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:57:00:INFO] [0524/085700.432363:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /www.a.fr/\n[08:57:23:INFO] [0524/085723.298013:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /www.a.fr/\n[08:57:24:INFO] 2019-05-24 08:57:24.100 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:57:24:INFO] 2019-05-24 08:57:24.101 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:57:24:INFO] 2019-05-24 08:57:24.101 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:57:24:INFO] 2019-05-24 08:57:24.101 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[08:57:25:INFO] Test Case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' passed (52.553 seconds).\n[08:57:25:INFO] Test Case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' started.\n[08:57:44:INFO] Test Case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' passed (18.485 seconds).\n[08:57:44:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' started.\n[08:58:04:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' passed (20.603 seconds).\n[08:58:04:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' started.\n[08:58:22:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' passed (17.338 seconds).\n[08:58:22:INFO] Test Suite 'BookmarksEntriesTestCase' passed at 2019-05-24 08:58:22.234.\n[08:58:22:INFO] \t Executed 16 tests, with 0 failures (0 unexpected) in 364.955 (364.975) seconds\n[08:58:22:INFO] Test Suite 'BookmarksFoldersTestCase' started at 2019-05-24 08:58:22.235\n[08:58:22:INFO] [0524/085822.236221:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:58:22:INFO] Test Case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' started.\n[08:58:24:INFO] [0524/085824.431462:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:58:43:INFO] Test Case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' passed (21.714 seconds).\n[08:58:43:INFO] Test Case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' started.\n[08:58:58:INFO] Test Case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' passed (14.942 seconds).\n[08:58:58:INFO] Test Case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' started.\n[08:59:16:INFO] Test Case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' passed (17.520 seconds).\n[08:59:16:INFO] Test Case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' started.\n[08:59:31:INFO] Test Case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' passed (15.182 seconds).\n[08:59:31:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' started.\n[08:59:59:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' passed (27.960 seconds).\n[08:59:59:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' started.\n[09:00:11:INFO] 2019-05-24 09:00:11.168 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:00:11:INFO] .\n[09:00:11:INFO] 2019-05-24 09:00:11.177 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Done.\n[09:00:15:INFO] 2019-05-24 09:00:15.171 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:00:15:INFO] .\n[09:00:15:INFO] 2019-05-24 09:00:15.179 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Done.\n[09:00:20:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' passed (20.960 seconds).\n[09:00:20:INFO] Test Case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' started.\n[09:00:37:INFO] Test Case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' passed (16.712 seconds).\n[09:00:37:INFO] Test Case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' started.\n[09:01:29:INFO] 2019-05-24 09:01:29.792 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:01:29:INFO] .\n[09:01:29:INFO] 2019-05-24 09:01:29.800 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Done.\n[09:01:36:INFO] Test Case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' passed (59.183 seconds).\n[09:01:36:INFO] Test Case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' started.\n[09:01:55:INFO] Test Case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' passed (18.933 seconds).\n[09:01:55:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' started.\n[09:02:23:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' passed (28.015 seconds).\n[09:02:23:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' started.\n[09:02:58:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' passed (34.939 seconds).\n[09:02:58:INFO] Test Case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' started.\n[09:03:31:INFO] Test Case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' passed (32.905 seconds).\n[09:03:31:INFO] Test Case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' started.\n[09:04:22:INFO] Test Case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' passed (51.379 seconds).\n[09:04:22:INFO] Test Case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' started.\n[09:04:27:INFO] [0524/090427.832656:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[09:04:57:INFO] Test Case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' passed (34.903 seconds).\n[09:04:57:INFO] Test Case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' started.\n[09:05:22:INFO] Test Case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' passed (24.608 seconds).\n[09:05:22:INFO] Test Suite 'BookmarksFoldersTestCase' passed at 2019-05-24 09:05:22.111.\n[09:05:22:INFO] \t Executed 15 tests, with 0 failures (0 unexpected) in 419.856 (419.877) seconds\n[09:05:22:INFO] Test Suite 'BookmarksPromoTestCase' started at 2019-05-24 09:05:22.112\n[09:05:22:INFO] [0524/090522.113363:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:05:22:INFO] Test Case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' started.\n[09:05:38:INFO] Test Case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' passed (16.129 seconds).\n[09:05:38:INFO] Test Case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' started.\n[09:05:50:INFO] Test Case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' passed (11.784 seconds).\n[09:05:50:INFO] Test Case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' started.\n[09:06:05:INFO] Test Case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' passed (15.749 seconds).\n[09:06:05:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' started.\n[09:06:19:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed (13.494 seconds).\n[09:06:19:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' started.\n[09:06:34:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed (15.596 seconds).\n[09:06:34:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' started.\n[09:06:52:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed (17.949 seconds).\n[09:06:52:INFO] Test Suite 'BookmarksPromoTestCase' passed at 2019-05-24 09:06:52.823.\n[09:06:52:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 90.700 (90.711) seconds\n[09:06:52:INFO] Test Suite 'BookmarksSearchTestCase' started at 2019-05-24 09:06:52.824\n[09:06:52:INFO] [0524/090652.825046:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:06:52:INFO] Test Case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' started.\n[09:07:06:INFO] Test Case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' passed (13.947 seconds).\n[09:07:06:INFO] Test Case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' started.\n[09:07:17:INFO] 2019-05-24 09:07:17.229 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key U.\n[09:07:17:INFO] 2019-05-24 09:07:17.233 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: U.\n[09:07:17:INFO] 2019-05-24 09:07:17.341 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key R.\n[09:07:17:INFO] 2019-05-24 09:07:17.444 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:07:17:INFO] 2019-05-24 09:07:17.507 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: R.\n[09:07:17:INFO] 2019-05-24 09:07:17.589 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key L.\n[09:07:17:INFO] 2019-05-24 09:07:17.692 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:07:17:INFO] 2019-05-24 09:07:17.746 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: L.\n[09:07:17:INFO] 2019-05-24 09:07:17.815 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:07:17:INFO] .\n[09:07:17:INFO] 2019-05-24 09:07:17.817 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Search.\n[09:07:35:INFO] Test Case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed (28.848 seconds).\n[09:07:35:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' started.\n[09:07:48:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' passed (12.909 seconds).\n[09:07:48:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' started.\n[09:07:59:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' passed (10.586 seconds).\n[09:07:59:INFO] Test Case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' started.\n[09:08:09:INFO] 2019-05-24 09:08:09.524 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key X.\n[09:08:09:INFO] 2019-05-24 09:08:09.530 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: X.\n[09:08:14:INFO] Test Case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' passed (15.752 seconds).\n[09:08:14:INFO] Test Case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' started.\n[09:08:25:INFO] 2019-05-24 09:08:25.309 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:08:25:INFO] 2019-05-24 09:08:25.318 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:08:25:INFO] 2019-05-24 09:08:25.436 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:08:25:INFO] 2019-05-24 09:08:25.437 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:08:25:INFO] 2019-05-24 09:08:25.502 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:08:25:INFO] 2019-05-24 09:08:25.502 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:08:25:INFO] 2019-05-24 09:08:25.568 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key s.\n[09:08:25:INFO] 2019-05-24 09:08:25.569 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: s.\n[09:08:25:INFO] 2019-05-24 09:08:25.625 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key t.\n[09:08:25:INFO] 2019-05-24 09:08:25.626 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: t.\n[09:08:25:INFO] 2019-05-24 09:08:25.682 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:08:25:INFO] .\n[09:08:25:INFO] 2019-05-24 09:08:25.684 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Search.\n[09:08:29:INFO] Test Case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' passed (14.836 seconds).\n[09:08:29:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' started.\n[09:08:40:INFO] 2019-05-24 09:08:40.141 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key U.\n[09:08:40:INFO] 2019-05-24 09:08:40.146 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: U.\n[09:08:40:INFO] 2019-05-24 09:08:40.242 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key R.\n[09:08:40:INFO] 2019-05-24 09:08:40.344 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:08:40:INFO] 2019-05-24 09:08:40.396 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: R.\n[09:08:40:INFO] 2019-05-24 09:08:40.471 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key L.\n[09:08:40:INFO] 2019-05-24 09:08:40.574 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:08:40:INFO] 2019-05-24 09:08:40.627 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: L.\n[09:08:40:INFO] 2019-05-24 09:08:40.697 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:08:40:INFO] .\n[09:08:40:INFO] 2019-05-24 09:08:40.700 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Search.\n[09:08:47:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' passed (17.730 seconds).\n[09:08:47:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' started.\n[09:08:57:INFO] 2019-05-24 09:08:57.924 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:08:57:INFO] 2019-05-24 09:08:57.928 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:08:58:INFO] 2019-05-24 09:08:58.051 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:08:58:INFO] 2019-05-24 09:08:58.053 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:08:58:INFO] 2019-05-24 09:08:58.116 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:08:58:INFO] 2019-05-24 09:08:58.116 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:08:58:INFO] 2019-05-24 09:08:58.175 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key s.\n[09:08:58:INFO] 2019-05-24 09:08:58.176 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: s.\n[09:08:58:INFO] 2019-05-24 09:08:58.233 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key t.\n[09:08:58:INFO] 2019-05-24 09:08:58.234 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: t.\n[09:08:58:INFO] 2019-05-24 09:08:58.298 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:08:58:INFO] .\n[09:08:58:INFO] 2019-05-24 09:08:58.300 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Search.\n[09:09:09:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' passed (22.331 seconds).\n[09:09:09:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' started.\n[09:09:17:INFO] 2019-05-24 09:09:17.910 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:09:17:INFO] 2019-05-24 09:09:17.915 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:09:18:INFO] 2019-05-24 09:09:18.058 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:09:18:INFO] 2019-05-24 09:09:18.059 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:09:18:INFO] 2019-05-24 09:09:18.124 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:09:18:INFO] 2019-05-24 09:09:18.124 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:09:18:INFO] 2019-05-24 09:09:18.197 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key s.\n[09:09:18:INFO] 2019-05-24 09:09:18.198 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: s.\n[09:09:18:INFO] 2019-05-24 09:09:18.264 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key t.\n[09:09:18:INFO] 2019-05-24 09:09:18.264 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: t.\n[09:09:18:INFO] 2019-05-24 09:09:18.328 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:09:18:INFO] .\n[09:09:18:INFO] 2019-05-24 09:09:18.330 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Search.\n[09:09:30:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' passed (20.855 seconds).\n[09:09:30:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' started.\n[09:09:41:INFO] 2019-05-24 09:09:41.106 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:09:41:INFO] 2019-05-24 09:09:41.109 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:09:41:INFO] 2019-05-24 09:09:41.230 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key o.\n[09:09:41:INFO] 2019-05-24 09:09:41.231 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: o.\n[09:09:41:INFO] 2019-05-24 09:09:41.303 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key l.\n[09:09:41:INFO] 2019-05-24 09:09:41.309 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: l.\n[09:09:41:INFO] 2019-05-24 09:09:41.390 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key d.\n[09:09:41:INFO] 2019-05-24 09:09:41.390 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: d.\n[09:09:41:INFO] 2019-05-24 09:09:41.467 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key e.\n[09:09:41:INFO] 2019-05-24 09:09:41.467 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: e.\n[09:09:41:INFO] 2019-05-24 09:09:41.537 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:09:41:INFO] 2019-05-24 09:09:41.538 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:09:41:INFO] 2019-05-24 09:09:41.612 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key .\n[09:09:41:INFO] 2019-05-24 09:09:41.614 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: space.\n[09:09:41:INFO] 2019-05-24 09:09:41.685 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key 1.\n[09:09:41:INFO] 2019-05-24 09:09:41.788 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: more, numbers.\n[09:09:41:INFO] 2019-05-24 09:09:41.856 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: 1.\n[09:09:47:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' passed (16.602 seconds).\n[09:09:47:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolders]' started.\n[09:10:04:INFO] 2019-05-24 09:10:04.879 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:10:04:INFO] 2019-05-24 09:10:04.913 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:10:05:INFO] 2019-05-24 09:10:05.054 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key o.\n[09:10:05:INFO] 2019-05-24 09:10:05.055 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: o.\n[09:10:05:INFO] 2019-05-24 09:10:05.126 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key l.\n[09:10:05:INFO] 2019-05-24 09:10:05.128 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: l.\n[09:10:05:INFO] 2019-05-24 09:10:05.216 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key d.\n[09:10:05:INFO] 2019-05-24 09:10:05.216 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: d.\n[09:10:05:INFO] 2019-05-24 09:10:05.291 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key e.\n[09:10:05:INFO] 2019-05-24 09:10:05.292 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: e.\n[09:10:05:INFO] 2019-05-24 09:10:05.367 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:10:05:INFO] 2019-05-24 09:10:05.368 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:10:05:INFO] 2019-05-24 09:10:05.441 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key .\n[09:10:05:INFO] 2019-05-24 09:10:05.443 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: space.\n[09:10:05:INFO] 2019-05-24 09:10:05.515 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key 1.\n[09:10:05:INFO] 2019-05-24 09:10:05.618 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: more, numbers.\n[09:10:05:INFO] 2019-05-24 09:10:05.671 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: 1.\n[09:10:05:INFO] 2019-05-24 09:10:05.731 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key ..\n[09:10:05:INFO] 2019-05-24 09:10:05.734 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: ..\n[09:10:05:INFO] 2019-05-24 09:10:05.805 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key 1.\n[09:10:05:INFO] 2019-05-24 09:10:05.806 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: 1.\n[09:10:12:INFO] 2019-05-24 09:10:12.408 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:10:12:INFO] 2019-05-24 09:10:12.443 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:10:12:INFO] 2019-05-24 09:10:12.564 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key o.\n[09:10:12:INFO] 2019-05-24 09:10:12.566 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: o.\n[09:10:12:INFO] 2019-05-24 09:10:12.645 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key l.\n[09:10:12:INFO] 2019-05-24 09:10:12.647 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: l.\n[09:10:12:INFO] 2019-05-24 09:10:12.734 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key d.\n[09:10:12:INFO] 2019-05-24 09:10:12.735 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: d.\n[09:10:12:INFO] 2019-05-24 09:10:12.814 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key e.\n[09:10:12:INFO] 2019-05-24 09:10:12.814 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: e.\n[09:10:12:INFO] 2019-05-24 09:10:12.890 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:10:12:INFO] 2019-05-24 09:10:12.890 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:10:12:INFO] 2019-05-24 09:10:12.963 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key .\n[09:10:12:INFO] 2019-05-24 09:10:12.964 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: space.\n[09:10:13:INFO] 2019-05-24 09:10:13.038 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key 2.\n[09:10:13:INFO] 2019-05-24 09:10:13.142 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: more, numbers.\n[09:10:13:INFO] 2019-05-24 09:10:13.196 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: 2.\n[09:10:17:INFO] 2019-05-24 09:10:17.044 ios_chrome_unified_consent_egtests[38803:911951] changing items while animating can result in a corrupted navigation bar\n[09:10:20:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolders]' passed (32.804 seconds).\n[09:10:20:INFO] Test Case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' started.\n[09:10:30:INFO] 2019-05-24 09:10:30.840 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:10:30:INFO] 2019-05-24 09:10:30.840 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:10:30:INFO] 2019-05-24 09:10:30.943 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:10:30:INFO] 2019-05-24 09:10:30.944 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:10:31:INFO] 2019-05-24 09:10:31.009 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:10:31:INFO] 2019-05-24 09:10:31.009 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:10:31:INFO] 2019-05-24 09:10:31.074 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key s.\n[09:10:31:INFO] 2019-05-24 09:10:31.074 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: s.\n[09:10:31:INFO] 2019-05-24 09:10:31.134 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key t.\n[09:10:31:INFO] 2019-05-24 09:10:31.134 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: t.\n[09:10:34:INFO] Test Case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' passed (14.577 seconds).\n[09:10:34:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' started.\n[09:10:45:INFO] 2019-05-24 09:10:45.086 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:10:45:INFO] 2019-05-24 09:10:45.122 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:10:45:INFO] 2019-05-24 09:10:45.248 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key o.\n[09:10:45:INFO] 2019-05-24 09:10:45.249 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: o.\n[09:10:45:INFO] 2019-05-24 09:10:45.327 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key l.\n[09:10:45:INFO] 2019-05-24 09:10:45.330 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: l.\n[09:10:45:INFO] 2019-05-24 09:10:45.416 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key d.\n[09:10:45:INFO] 2019-05-24 09:10:45.417 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: d.\n[09:10:45:INFO] 2019-05-24 09:10:45.496 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key e.\n[09:10:45:INFO] 2019-05-24 09:10:45.497 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: e.\n[09:10:45:INFO] 2019-05-24 09:10:45.572 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:10:45:INFO] 2019-05-24 09:10:45.573 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:10:45:INFO] 2019-05-24 09:10:45.647 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key .\n[09:10:45:INFO] 2019-05-24 09:10:45.649 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: space.\n[09:10:45:INFO] 2019-05-24 09:10:45.724 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key 1.\n[09:10:45:INFO] 2019-05-24 09:10:45.828 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: more, numbers.\n[09:10:45:INFO] 2019-05-24 09:10:45.881 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: 1.\n[09:10:45:INFO] 2019-05-24 09:10:45.941 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key ..\n[09:10:45:INFO] 2019-05-24 09:10:45.944 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: ..\n[09:10:46:INFO] 2019-05-24 09:10:46.016 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key 1.\n[09:10:46:INFO] 2019-05-24 09:10:46.016 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: 1.\n[09:10:57:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' passed (22.677 seconds).\n[09:10:57:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' started.\n[09:11:07:INFO] 2019-05-24 09:11:07.651 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:11:07:INFO] 2019-05-24 09:11:07.685 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:11:07:INFO] 2019-05-24 09:11:07.809 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:11:07:INFO] 2019-05-24 09:11:07.809 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:11:07:INFO] 2019-05-24 09:11:07.875 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:11:07:INFO] 2019-05-24 09:11:07.876 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:11:07:INFO] 2019-05-24 09:11:07.939 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key s.\n[09:11:07:INFO] 2019-05-24 09:11:07.940 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: s.\n[09:11:08:INFO] 2019-05-24 09:11:08.001 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key t.\n[09:11:08:INFO] 2019-05-24 09:11:08.002 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: t.\n[09:11:19:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' passed (22.082 seconds).\n[09:11:19:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' started.\n[09:11:36:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' passed (16.627 seconds).\n[09:11:36:INFO] Test Case '-[BookmarksSearchTestCase testSearchResults]' started.\n[09:11:47:INFO] 2019-05-24 09:11:47.087 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key o.\n[09:11:47:INFO] 2019-05-24 09:11:47.291 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:11:47:INFO] 2019-05-24 09:11:47.367 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: o.\n[09:11:48:INFO] 2019-05-24 09:11:48.420 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key n.\n[09:11:48:INFO] 2019-05-24 09:11:48.422 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: n.\n[09:11:50:INFO] 2019-05-24 09:11:50.286 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key y.\n[09:11:50:INFO] 2019-05-24 09:11:50.287 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: y.\n[09:11:54:INFO] Test Case '-[BookmarksSearchTestCase testSearchResults]' passed (18.609 seconds).\n[09:11:54:INFO] Test Case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' started.\n[09:12:05:INFO] 2019-05-24 09:12:05.415 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:12:05:INFO] 2019-05-24 09:12:05.620 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:12:05:INFO] 2019-05-24 09:12:05.695 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:12:06:INFO] 2019-05-24 09:12:06.133 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key \b.\n[09:12:06:INFO] 2019-05-24 09:12:06.134 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: delete.\n[09:12:10:INFO] Test Case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' passed (16.356 seconds).\n[09:12:10:INFO] Test Case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' started.\n[09:12:26:INFO] Test Case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' passed (15.913 seconds).\n[09:12:26:INFO] Test Case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' started.\n[09:12:37:INFO] 2019-05-24 09:12:37.357 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key F.\n[09:12:37:INFO] 2019-05-24 09:12:37.359 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: F.\n[09:12:37:INFO] 2019-05-24 09:12:37.518 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key i.\n[09:12:37:INFO] 2019-05-24 09:12:37.519 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: i.\n[09:12:37:INFO] 2019-05-24 09:12:37.590 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key r.\n[09:12:37:INFO] 2019-05-24 09:12:37.590 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: r.\n[09:12:37:INFO] 2019-05-24 09:12:37.656 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key s.\n[09:12:37:INFO] 2019-05-24 09:12:37.657 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: s.\n[09:12:37:INFO] 2019-05-24 09:12:37.719 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key t.\n[09:12:37:INFO] 2019-05-24 09:12:37.720 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: t.\n[09:12:37:INFO] 2019-05-24 09:12:37.777 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key .\n[09:12:37:INFO] 2019-05-24 09:12:37.779 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: space.\n[09:12:37:INFO] 2019-05-24 09:12:37.838 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key U.\n[09:12:37:INFO] 2019-05-24 09:12:37.940 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:12:37:INFO] 2019-05-24 09:12:37.991 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: U.\n[09:12:38:INFO] 2019-05-24 09:12:38.058 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key R.\n[09:12:38:INFO] 2019-05-24 09:12:38.161 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:12:38:INFO] 2019-05-24 09:12:38.215 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: R.\n[09:12:38:INFO] 2019-05-24 09:12:38.283 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key L.\n[09:12:38:INFO] 2019-05-24 09:12:38.385 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:12:38:INFO] 2019-05-24 09:12:38.438 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: L.\n[09:12:43:INFO] Test Case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' passed (16.937 seconds).\n[09:12:43:INFO] Test Case '-[BookmarksSearchTestCase testSearchWithNoResults]' started.\n[09:12:54:INFO] 2019-05-24 09:12:54.290 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key z.\n[09:12:54:INFO] 2019-05-24 09:12:54.494 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: shift.\n[09:12:54:INFO] 2019-05-24 09:12:54.573 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: z.\n[09:12:54:INFO] 2019-05-24 09:12:54.649 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key z.\n[09:12:54:INFO] 2019-05-24 09:12:54.650 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: z.\n[09:12:54:INFO] 2019-05-24 09:12:54.707 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:12:54:INFO] .\n[09:12:54:INFO] 2019-05-24 09:12:54.709 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Search.\n[09:12:58:INFO] Test Case '-[BookmarksSearchTestCase testSearchWithNoResults]' passed (14.937 seconds).\n[09:12:58:INFO] Test Suite 'BookmarksSearchTestCase' passed at 2019-05-24 09:12:58.758.\n[09:12:58:INFO] \t Executed 20 tests, with 0 failures (0 unexpected) in 365.915 (365.934) seconds\n[09:12:58:INFO] Test Suite 'BookmarksTestCase' started at 2019-05-24 09:12:58.758\n[09:12:58:INFO] [0524/091258.759846:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:12:58:INFO] Test Case '-[BookmarksTestCase testAddRemoveBookmark]' started.\n[09:13:00:INFO] [0524/091300.979828:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:13:33:INFO] Test Case '-[BookmarksTestCase testAddRemoveBookmark]' passed (34.880 seconds).\n[09:13:33:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' started.\n[09:13:52:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' passed (18.885 seconds).\n[09:13:52:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' started.\n[09:14:11:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' passed (18.935 seconds).\n[09:14:11:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarShown]' started.\n[09:14:24:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarShown]' passed (12.794 seconds).\n[09:14:24:INFO] Test Case '-[BookmarksTestCase testBookmarkMultipleTabs]' started.\n[09:14:41:INFO] Test Case '-[BookmarksTestCase testBookmarkMultipleTabs]' passed (17.679 seconds).\n[09:14:41:INFO] Test Case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' started.\n[09:14:59:INFO] 2019-05-24 09:14:59.743 ios_chrome_unified_consent_egtests[38803:911951] Attempting to type key\n[09:14:59:INFO] .\n[09:14:59:INFO] 2019-05-24 09:14:59.754 ios_chrome_unified_consent_egtests[38803:911951] Tapping on key: Done.\n[09:15:05:INFO] Test Case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' passed (23.265 seconds).\n[09:15:05:INFO] Test Case '-[BookmarksTestCase testBookmarksURLDisabled]' started.\n[09:15:10:INFO] Test Case '-[BookmarksTestCase testBookmarksURLDisabled]' passed (5.144 seconds).\n[09:15:10:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreated]' started.\n[09:15:42:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreated]' passed (32.003 seconds).\n[09:15:42:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' started.\n[09:16:11:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' passed (28.753 seconds).\n[09:16:11:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' started.\n[09:16:34:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' passed (23.833 seconds).\n[09:16:34:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' started.\n[09:16:54:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' passed (19.250 seconds).\n[09:16:54:INFO] Test Case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' started.\n[09:17:19:INFO] Test Case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' passed (24.965 seconds).\n[09:17:19:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' started.\n[09:17:40:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' passed (21.497 seconds).\n[09:17:40:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' started.\n[09:17:48:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' passed (8.278 seconds).\n[09:17:48:INFO] Test Case '-[BookmarksTestCase testMoveDoesSaveOnSave]' started.\n[09:18:17:INFO] Test Case '-[BookmarksTestCase testMoveDoesSaveOnSave]' passed (28.263 seconds).\n[09:18:17:INFO] Test Case '-[BookmarksTestCase testNavigateBackWithGesture]' started.\n[09:18:36:INFO] Test Case '-[BookmarksTestCase testNavigateBackWithGesture]' passed (19.503 seconds).\n[09:18:36:INFO] Test Suite 'BookmarksTestCase' passed at 2019-05-24 09:18:36.705.\n[09:18:36:INFO] \t Executed 16 tests, with 0 failures (0 unexpected) in 337.928 (337.946) seconds\n[09:18:36:INFO] Test Suite 'FirstRunTestCase' started at 2019-05-24 09:18:36.705\n[09:18:36:INFO] [0524/091836.706876:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:18:36:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' started.\n[09:18:44:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' passed (7.563 seconds).\n[09:18:44:INFO] Test Case '-[FirstRunTestCase testPrivacy]' started.\n[09:18:51:INFO] Test Case '-[FirstRunTestCase testPrivacy]' passed (7.648 seconds).\n[09:18:51:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' started.\n[09:18:56:INFO] [0524/091856.310808:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:18:56:INFO] [0524/091856.314086:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:18:56:INFO] [0524/091856.330652:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:18:56:INFO] [0524/091856.330953:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:18:56:INFO] [0524/091856.410471:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:18:56:INFO] [0524/091856.410639:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:18:59:INFO] [0524/091859.709487:INFO:profile_sync_service.cc(1746)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:19:00:INFO] [0524/091900.928989:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:19:00:INFO] [0524/091900.965108:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:19:00:INFO] [0524/091900.965612:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:19:01:INFO] [0524/091901.285172:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[09:19:03:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' passed (11.116 seconds).\n[09:19:03:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' started.\n[09:19:04:INFO] [0524/091904.923039:WARNING:first_run_egtest.mm(203)] Skipping test as there is no undo operation when Unified Consent is enabled.\n[09:19:06:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' passed (3.784 seconds).\n[09:19:06:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' started.\n[09:19:14:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' passed (7.587 seconds).\n[09:19:14:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' started.\n[09:19:21:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' passed (6.787 seconds).\n[09:19:21:INFO] Test Suite 'FirstRunTestCase' passed at 2019-05-24 09:19:21.202.\n[09:19:21:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 44.485 (44.497) seconds\n[09:19:21:INFO] Test Suite 'GoogleServicesSettingsTestCase' started at 2019-05-24 09:19:21.203\n[09:19:21:INFO] [0524/091921.204157:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:19:21:INFO] Test Case '-[GoogleServicesSettingsTestCase testInterruptSigninFromGoogleServicesSettings]' started.\n[09:19:32:INFO] [0524/091932.896715:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:19:32:INFO] [0524/091932.898806:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:19:33:INFO] [0524/091933.089748:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:19:33:INFO] [0524/091933.089908:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:19:33:INFO] [0524/091933.091335:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:19:33:INFO] [0524/091933.091462:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:19:48:INFO] [0524/091948.873047:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:19:48:INFO] [0524/091948.992286:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:19:49:INFO] [0524/091948.992505:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:19:52:INFO] Test Case '-[GoogleServicesSettingsTestCase testInterruptSigninFromGoogleServicesSettings]' passed (30.856 seconds).\n[09:19:52:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpenGoogleServicesSettings]' started.\n[09:20:05:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpenGoogleServicesSettings]' passed (13.631 seconds).\n[09:20:05:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpeningServices]' started.\n[09:20:19:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpeningServices]' passed (13.521 seconds).\n[09:20:19:INFO] Test Suite 'GoogleServicesSettingsTestCase' passed at 2019-05-24 09:20:19.226.\n[09:20:19:INFO] \t Executed 3 tests, with 0 failures (0 unexpected) in 58.009 (58.023) seconds\n[09:20:19:INFO] Test Suite 'SettingsTestCase' started at 2019-05-24 09:20:19.226\n[09:20:19:INFO] [0524/092019.227787:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:20:19:INFO] Test Case '-[SettingsTestCase testAccessibilityOnAddresses]' started.\n[09:20:34:INFO] Test Case '-[SettingsTestCase testAccessibilityOnAddresses]' passed (14.787 seconds).\n[09:20:34:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthManagementSettingsPage]' started.\n[09:20:50:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthManagementSettingsPage]' passed (16.507 seconds).\n[09:20:50:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthPreloadWebpagesSettingsPage]' started.\n[09:21:09:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthPreloadWebpagesSettingsPage]' passed (18.633 seconds).\n[09:21:09:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsBlockPopupsPage]' started.\n[09:21:26:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsBlockPopupsPage]' passed (16.913 seconds).\n[09:21:26:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsPage]' started.\n[09:21:40:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsPage]' passed (14.680 seconds).\n[09:21:40:INFO] Test Case '-[SettingsTestCase testAccessibilityOnGoogleChrome]' started.\n[09:21:57:INFO] Test Case '-[SettingsTestCase testAccessibilityOnGoogleChrome]' passed (16.519 seconds).\n[09:21:57:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPaymentMethods]' started.\n[09:22:11:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPaymentMethods]' passed (14.693 seconds).\n[09:22:11:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyClearBrowsingHistoryPage]' started.\n[09:22:29:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyClearBrowsingHistoryPage]' passed (17.127 seconds).\n[09:22:29:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyHandoffSettingsPage]' started.\n[09:22:46:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyHandoffSettingsPage]' passed (16.954 seconds).\n[09:22:46:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacySettingsPage]' started.\n[09:23:00:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacySettingsPage]' passed (14.725 seconds).\n[09:23:00:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSearchEngine]' started.\n[09:23:15:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSearchEngine]' passed (14.696 seconds).\n[09:23:15:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSendUsageData]' started.\n[09:23:17:INFO] 2019-05-24 09:23:17.359 ios_chrome_unified_consent_egtests[38803:911951] -- Earl Grey Test Disabled -- Privacy switch for ContentSuggestion was moved to the Sync and Google services settings screen, so it is no longer present in the privacy section. This test is now covered by -[GoogleServicesSettingsTestCase testOpeningServices].\n[09:23:19:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSendUsageData]' passed (3.866 seconds).\n[09:23:19:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSettingsPage]' started.\n[09:23:29:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSettingsPage]' passed (10.175 seconds).\n[09:23:29:INFO] Test Case '-[SettingsTestCase testAccessibilityOnVoiceSearch]' started.\n[09:23:44:INFO] Test Case '-[SettingsTestCase testAccessibilityOnVoiceSearch]' passed (14.713 seconds).\n[09:23:44:INFO] Test Case '-[SettingsTestCase testBreakpadReporting]' started.\n[09:23:48:INFO] Test Case '-[SettingsTestCase testBreakpadReporting]' passed (3.885 seconds).\n[09:23:48:INFO] Test Case '-[SettingsTestCase testBreakpadReportingFirstLaunch]' started.\n[09:23:51:INFO] Test Case '-[SettingsTestCase testBreakpadReportingFirstLaunch]' passed (3.846 seconds).\n[09:23:51:INFO] Test Case '-[SettingsTestCase testClearCookies]' started.\n[09:23:54:INFO] [0524/092354.114405:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:24:16:INFO] Test Case '-[SettingsTestCase testClearCookies]' passed (24.135 seconds).\n[09:24:16:INFO] Test Case '-[SettingsTestCase testMetricsReporting]' started.\n[09:24:19:INFO] Test Case '-[SettingsTestCase testMetricsReporting]' passed (3.864 seconds).\n[09:24:19:INFO] Test Case '-[SettingsTestCase testSettingsKeyboardCommands]' started.\n[09:24:33:INFO] Test Case '-[SettingsTestCase testSettingsKeyboardCommands]' passed (13.197 seconds).\n[09:24:33:INFO] Test Case '-[SettingsTestCase testSettingsSignedOutIncognito]' started.\n[09:24:42:INFO] 2019-05-24 09:24:42.950 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[09:24:42:INFO] 2019-05-24 09:24:42.950 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[09:24:42:INFO] 2019-05-24 09:24:42.950 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[09:24:42:INFO] 2019-05-24 09:24:42.951 ios_chrome_unified_consent_egtests[38803:911951] could not create directory \"\" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 \"The file \u201c\u201d couldn\u2019t be saved.\" UserInfo={NSFilePath=}\n[09:24:44:INFO] Test Case '-[SettingsTestCase testSettingsSignedOutIncognito]' passed (11.810 seconds).\n[09:24:44:INFO] Test Suite 'SettingsTestCase' passed at 2019-05-24 09:24:44.980.\n[09:24:44:INFO] \t Executed 20 tests, with 0 failures (0 unexpected) in 265.725 (265.753) seconds\n[09:24:44:INFO] Test Suite 'SigninInteractionControllerTestCase' started at 2019-05-24 09:24:44.980\n[09:24:44:INFO] [0524/092444.981875:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:24:45:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' started.\n[09:24:55:INFO] [0524/092454.937545:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:24:55:INFO] [0524/092454.939729:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:24:55:INFO] [0524/092454.991334:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:24:55:INFO] [0524/092454.994092:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:24:55:INFO] [0524/092455.109871:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:24:55:INFO] [0524/092455.109982:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:24:55:INFO] [0524/092455.227458:WARNING:spdy_session.cc(3317)] Received HEADERS for invalid stream 1\n[09:24:55:INFO] [0524/092455.228634:WARNING:spdy_session.cc(3317)] Received HEADERS for invalid stream 3\n[09:24:55:INFO] [0524/092455.302024:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:24:55:INFO] [0524/092455.302201:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[09:24:55:INFO] [0524/092455.546484:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:24:55:INFO] [0524/092455.546642:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[09:25:07:INFO] [0524/092507.198094:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:25:07:INFO] [0524/092507.243708:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:25:07:INFO] [0524/092507.243899:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:25:28:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' passed (43.013 seconds).\n[09:25:28:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' started.\n[09:25:53:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' passed (25.326 seconds).\n[09:25:53:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' started.\n[09:26:00:INFO] 2019-05-24 09:26:00.795 ios_chrome_unified_consent_egtests[38803:911951] Warning: Attempt to present <IdentityChooserViewController: 0x7f8910cd4eb0> on <UnifiedConsentViewController: 0x7f8910cd13b0> whose view is not in the window hierarchy!\n[09:26:16:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' passed (22.678 seconds).\n[09:26:16:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' started.\n[09:26:26:INFO] [0524/092626.681615:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:26:26:INFO] [0524/092626.681761:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:26:26:INFO] [0524/092626.732399:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:26:26:INFO] [0524/092626.732578:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:26:26:INFO] [0524/092626.829287:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:26:38:INFO] [0524/092638.789924:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:26:38:INFO] [0524/092638.836718:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:26:38:INFO] [0524/092638.836910:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:26:44:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' passed (28.643 seconds).\n[09:26:44:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' started.\n[09:26:55:INFO] [0524/092655.313135:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:26:55:INFO] [0524/092655.313283:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:26:55:INFO] [0524/092655.316473:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:26:55:INFO] [0524/092655.318063:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:26:55:INFO] [0524/092655.464038:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:26:59:INFO] [0524/092659.162907:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:26:59:INFO] [0524/092659.197471:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:26:59:INFO] [0524/092659.197672:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:27:01:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' passed (16.598 seconds).\n[09:27:01:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' started.\n[09:27:11:INFO] [0524/092711.977916:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:27:11:INFO] [0524/092711.978132:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:27:12:INFO] [0524/092712.026473:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:27:12:INFO] [0524/092712.026613:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:27:15:INFO] [0524/092715.287983:INFO:profile_sync_service.cc(1746)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:27:15:INFO] [0524/092715.524941:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:27:16:INFO] [0524/092716.869750:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:27:17:INFO] [0524/092717.022068:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:27:17:INFO] [0524/092717.022346:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:27:20:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' passed (18.767 seconds).\n[09:27:20:INFO] Test Suite 'SigninInteractionControllerTestCase' passed at 2019-05-24 09:27:20.027.\n[09:27:20:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 155.025 (155.047) seconds\n[09:27:20:INFO] Test Suite 'SigninInteractionControllerUnityEnabledTestCase' started at 2019-05-24 09:27:20.028\n[09:27:20:INFO] [0524/092720.029014:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:27:20:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testAddAccountAutomatically]' started.\n[09:27:32:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testAddAccountAutomatically]' passed (12.032 seconds).\n[09:27:32:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testRemoveLastAccount]' started.\n[09:27:44:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testRemoveLastAccount]' passed (12.815 seconds).\n[09:27:44:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testSignInSwitchAccountsAndImportData]' started.\n[09:27:55:INFO] [0524/092755.157086:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:27:55:INFO] [0524/092755.159008:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:27:55:INFO] [0524/092755.239865:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:27:55:INFO] [0524/092755.240350:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:27:55:INFO] [0524/092755.270374:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:27:55:INFO] [0524/092755.270774:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:27:55:INFO] [0524/092755.319948:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:27:55:INFO] [0524/092755.320199:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[09:27:55:INFO] [0524/092755.384996:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:27:55:INFO] [0524/092755.385234:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[09:28:07:INFO] [0524/092807.419949:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:28:07:INFO] [0524/092807.464356:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:28:07:INFO] [0524/092807.464544:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:28:17:INFO] [0524/092817.008688:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:28:17:INFO] [0524/092817.009224:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:28:17:INFO] [0524/092817.011807:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:28:17:INFO] [0524/092817.012250:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:28:17:INFO] [0524/092817.046836:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:28:17:INFO] [0524/092817.047264:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[09:28:17:INFO] [0524/092817.094926:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:28:17:INFO] [0524/092817.171613:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:28:17:INFO] [0524/092817.171772:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[09:28:19:INFO] [0524/092819.597419:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:28:19:INFO] [0524/092819.637621:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:28:19:INFO] [0524/092819.638719:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:28:23:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testSignInSwitchAccountsAndImportData]' passed (38.164 seconds).\n[09:28:23:INFO] Test Suite 'SigninInteractionControllerUnityEnabledTestCase' passed at 2019-05-24 09:28:23.050.\n[09:28:23:INFO] \t Executed 3 tests, with 0 failures (0 unexpected) in 63.011 (63.022) seconds\n[09:28:23:INFO] Test Suite 'SigninSettingsTestCase' started at 2019-05-24 09:28:23.051\n[09:28:23:INFO] [0524/092823.052040:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:28:23:INFO] Test Case '-[SigninSettingsTestCase testAutomaticSigninPromoDismiss]' started.\n[09:28:39:INFO] Test Case '-[SigninSettingsTestCase testAutomaticSigninPromoDismiss]' passed (16.609 seconds).\n[09:28:39:INFO] Test Case '-[SigninSettingsTestCase testDissmissSigninPromo]' started.\n[09:28:50:INFO] Test Case '-[SigninSettingsTestCase testDissmissSigninPromo]' passed (11.004 seconds).\n[09:28:50:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithColdStateUsingPrimaryButton]' started.\n[09:29:04:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed (13.575 seconds).\n[09:29:04:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' started.\n[09:29:12:INFO] [0524/092912.888911:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:29:12:INFO] [0524/092912.892483:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:29:12:INFO] [0524/092912.957838:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:29:12:INFO] [0524/092912.958410:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:29:13:INFO] [0524/092913.001458:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:29:13:INFO] [0524/092913.001631:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:29:15:INFO] [0524/092915.219294:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:29:15:INFO] [0524/092915.374382:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:29:15:INFO] [0524/092915.374554:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:29:18:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed (14.130 seconds).\n[09:29:18:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' started.\n[09:29:29:INFO] [0524/092929.785331:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:29:29:INFO] [0524/092929.785663:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:29:29:INFO] [0524/092929.840055:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:29:29:INFO] [0524/092929.841116:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:29:29:INFO] [0524/092929.940814:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:29:32:INFO] [0524/092932.106530:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:29:32:INFO] [0524/092932.263297:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:29:32:INFO] [0524/092932.263472:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:29:35:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed (16.886 seconds).\n[09:29:35:INFO] Test Suite 'SigninSettingsTestCase' passed at 2019-05-24 09:29:35.267.\n[09:29:35:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 72.205 (72.216) seconds\n[09:29:35:INFO] Test Suite 'TranslateUITestCase' started at 2019-05-24 09:29:35.268\n[09:29:35:INFO] [0524/092935.269113:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:29:35:INFO] Test Case '-[TranslateUITestCase testAccessibilityOfTranslateSettings]' started.\n[09:29:54:INFO] Test Case '-[TranslateUITestCase testAccessibilityOfTranslateSettings]' passed (19.090 seconds).\n[09:29:54:INFO] Test Suite 'TranslateUITestCase' passed at 2019-05-24 09:29:54.366.\n[09:29:54:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 19.090 (19.099) seconds\n[09:29:54:INFO] Test Suite 'ios_chrome_unified_consent_egtests.app' passed at 2019-05-24 09:29:54.367.\n[09:29:54:INFO] \t Executed 138 tests, with 0 failures (0 unexpected) in 2581.447 (2583.553) seconds\n[09:29:54:INFO] Test Suite 'All tests' passed at 2019-05-24 09:29:54.369.\n[09:29:54:INFO] \t Executed 138 tests, with 0 failures (0 unexpected) in 2581.447 (2583.555) seconds\n[09:29:54:INFO] \n[09:29:54:INFO] \n[09:29:54:INFO] Test session results and logs:\n[09:29:54:INFO] \t/Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-bxwrbforqytmxdftuexcbqnxtaiw/Logs/Test/Test-Transient Testing-2019.05.24_08-46-11--0700.xcresult\n[09:29:54:INFO] \n[09:29:54:INFO] 2019-05-24 09:29:54.878 xcodebuild[38680:910663] [MT] IDETestOperationsObserverDebug: 2623.017 elapsed -- Testing started completed.\n[09:29:54:INFO] 2019-05-24 09:29:54.878 xcodebuild[38680:910663] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[09:29:54:INFO] 2019-05-24 09:29:54.878 xcodebuild[38680:910663] [MT] IDETestOperationsObserverDebug: 2623.017 sec, +2623.017 sec -- end\n[09:29:54:INFO] ** TEST EXECUTE SUCCEEDED **\n[09:29:54:INFO] \n[09:29:59:DEBUG] Finished print_process_output.\n[09:29:59:INFO] Waiting for test process to terminate.\n[09:29:59:INFO] Test process terminated.\n[09:29:59:DEBUG] Setting sigterm handler.\n[09:29:59:DEBUG] Stdout flushed after test process.\n[09:29:59:DEBUG] Processing test results.\n[09:29:59:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0\n\n[09:29:59:DEBUG] Extracting test data.\n[09:29:59:DEBUG] Retrieving crash reports.\n[09:29:59:DEBUG] Retrieving derived data.\n[09:29:59:DEBUG] Making desktop screenshots.\n[09:29:59:DEBUG] Killing simulators.\n[09:29:59:INFO] Killing simulators.\n[09:30:04:DEBUG] Wiping simulator.\nDevice wiped.\n[09:30:07:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "77d9acf00ce8c45262c7379ccaba3d1c27218bef", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "4503567744497e11", "server_versions": [ "4303-bae210e" ], "started_ts": "2019-05-24T15:45:43.520737", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios-simulator-full-configs", "buildnumber:14972", "data:8597aa03e37bdaaedfce936b5328ee54e76ddd50", "device_type:iPhone X", "ios_version:11.4", "master:chromium.mac", "name:ios_chrome_unified_consent_egtests", "os:Mac-10.13.6", "os:Mac-10.14.3", "os:Mac-10.14.4", "platform:simulator", "pool:Chrome", "priority:30", "service_account:ios-isolated-tester@chops-service-accounts.iam.gserviceaccount.com", "slavename:vm124-m9", "spec_name:chromium.ci:ios-simulator-full-configs", "stepname:ios_chrome_unified_consent_egtests (iPhone X iOS 11.4) (with patch) on Mac-10.13.6", "swarming.pool.template:none", "swarming.pool.version:d3e7bc6f4b778581f83125cfcf812995e0ee370a", "test:ios_chrome_unified_consent_egtests", "user:None" ], "task_id": "4503567744497e10", "try_number": "1" } ] }