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": [ "build296-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": "build296-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:26:51.765461", "costs_usd": [ 0.344330370455873 ], "created_ts": "2019-05-24T15:44:29.103873", "duration": 2489.5375339984894, "modified_ts": "2019-05-24T16:26:51.765461", "name": "ios_chrome_unified_consent_egtests (iPhone 7 iOS 11.4) (with patch)/Mac-10.13.6/a502073ded/ios-simulator-full-configs/14972", "output": "[I 2019-05-24 08:44:48] About to install Xcode 10b61 in Xcode.app for ios\n[08:44:49:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\n[08:44:49:INFO] Killing simulators.\nDevice wiped.\n[08:44:56:DEBUG] Setting sigterm handler.\n[08:44:58:INFO] User defaults from command line:\n[08:44:58:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/2B34DD3D-6278-4C93-8BC0-B4B781260A65\n[08:44:58:INFO] \n[08:44:58:INFO] Testing started on 'iPhone 7'\n[08:44:58:INFO] 2019-05-24 08:44:58.602 xcodebuild[17087:245982] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[08:44:58:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-afyckwohwxrgovbiqspqoddqylrs/Logs/Test/Test-Transient Testing-2019.05.24_08-44-58--0700.xcresult/1_Test/Diagnostics/ios_chrome_unified_consent_egtests_module-4A6C75E6-4D7F-4BFB-8D8E-701057ACCB3B/ios_chrome_unified_consent_egtests_module-2F3C0946-8CDB-4699-92C0-1946A58E8E02/Session-ios_chrome_unified_consent_egtests_module-2019-05-24_084458-tQ7uUK.log\n[08:44:58:INFO] 2019-05-24 08:44:58.602 xcodebuild[17087:245963] [MT] IDETestOperationsObserverDebug: (A7AF8153-6606-4209-9133-2408C33FEF85) Beginning test session ios_chrome_unified_consent_egtests_module-A7AF8153-6606-4209-9133-2408C33FEF85 at 2019-05-24 08:44:58.602 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7faf0a5be840> {\n[08:44:58:INFO] \t\tSimDevice: iPhone 7 (A89A4669-A80D-4B3E-A7F4-E97575E48817, iOS 11.4, Shutdown)\n[08:44:58:INFO] } (11.4 (15F79))\n[08:45:24:INFO] 2019-05-24 08:45:24.432 ios_chrome_unified_consent_egtests[17210:247228] Crash handler setup started.\n[08:45:24:INFO] 2019-05-24 08:45:24.432 ios_chrome_unified_consent_egtests[17210:247228] Crash handler setup completed.\n[08:45:24:INFO] 2019-05-24 08:45:24.433 ios_chrome_unified_consent_egtests[17210:247228] Enabling accessibility for automation on Simulator.\n[08:45:24:INFO] objc[17210]: 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 (0x140d624d0) 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 (0x140b78e38). One of the two will be used. Which one is undefined.\n[08:45:26:INFO] 2019-05-24 08:45:26.338 ios_chrome_unified_consent_egtests[17210:247228] 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:45:26:INFO] 2019-05-24 08:45:26.339 ios_chrome_unified_consent_egtests[17210:247228] 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:45:26:INFO] 2019-05-24 08:45:26.339 ios_chrome_unified_consent_egtests[17210:247228] 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:45:26:INFO] 2019-05-24 08:45:26.339 ios_chrome_unified_consent_egtests[17210:247228] 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:45:27:INFO] 2019-05-24 08:45:27.380 ios_chrome_unified_consent_egtests[17210:247228] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fc2ee952a00>.\n[08:45:27:INFO] [0524/084527.786084: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:45:28:INFO] [0524/084528.162830:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/A89A4669-A80D-4B3E-A7F4-E97575E48817/data/Containers/Data/Application/57C9452E-BEA9-488E-8578-1405BCF62337/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/A89A4669-A80D-4B3E-A7F4-E97575E48817/data/Containers/Data/Application/57C9452E-BEA9-488E-8578-1405BCF62337/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[08:45:35:INFO] Test Suite 'All tests' started at 2019-05-24 08:45:35.309\n[08:45:35:INFO] Test Suite 'ios_chrome_unified_consent_egtests.app' started at 2019-05-24 08:45:35.310\n[08:45:35:INFO] Test Suite 'AccountCollectionsTestCase' started at 2019-05-24 08:45:35.311\n[08:45:35:INFO] [0524/084535.319981:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:45:37:INFO] Test Case '-[AccountCollectionsTestCase testMDMError]' started.\n[08:45:39:INFO] 2019-05-24 08:45:39.343 ios_chrome_unified_consent_egtests[17210:247228] -- 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:45:41:INFO] Test Case '-[AccountCollectionsTestCase testMDMError]' passed (4.299 seconds).\n[08:45:41:INFO] Test Case '-[AccountCollectionsTestCase testSignInDisconnectCancelled]' started.\n[08:45:50:INFO] 2019-05-24 08:45:50.669 ios_chrome_unified_consent_egtests[17210:247228] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fc2ed7f7800; baseClass = UIButton; frame = (16 615; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x6000004f7980>>, Touch Target: {86, 36}\n[08:45:51:INFO] [0524/084551.279358:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[08:45:51:INFO] [0524/084551.315859:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[08:45:51:INFO] [0524/084551.326956:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:45:51:INFO] [0524/084551.327160:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:45:51:INFO] [0524/084551.405737:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:45:51:INFO] [0524/084551.405966:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:46:02:INFO] [0524/084602.971032:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:46:03:INFO] [0524/084602.998502:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:46:03:INFO] [0524/084602.999891:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:46:03:INFO] [0524/084603.327203:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:46:04:INFO] [0524/084604.379434:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:46:06:INFO] Test Case '-[AccountCollectionsTestCase testSignInDisconnectCancelled]' passed (24.804 seconds).\n[08:46:06:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnDisconnectAccount]' started.\n[08:46:07:INFO] [0524/084607.403657:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:46:15:INFO] [0524/084615.968401:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:46:16:INFO] [0524/084615.968584:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:46:16:INFO] [0524/084616.026812:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:46:16:INFO] [0524/084616.027464:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:46:16:INFO] [0524/084616.128332:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:46:16:INFO] [0524/084616.405477:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:46:26:INFO] [0524/084626.756689:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:46:26:INFO] [0524/084626.820395:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:46:26:INFO] [0524/084626.820579:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:46:31:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnDisconnectAccount]' passed (25.594 seconds).\n[08:46:31:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnSyncSettings]' started.\n[08:46:38:INFO] [0524/084638.411944:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:46:41:INFO] [0524/084641.523010:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:46:41:INFO] [0524/084641.523685:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:46:41:INFO] [0524/084641.574596:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:46:41:INFO] [0524/084641.574753:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:46:41:INFO] [0524/084641.690675:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:46:51:INFO] [0524/084651.380411:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:46:51:INFO] [0524/084651.435779:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:46:51:INFO] [0524/084651.435951:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:46:56:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnSyncSettings]' passed (24.618 seconds).\n[08:46:56:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadOnRemoveAccount]' started.\n[08:47:06:INFO] [0524/084706.206891:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:47:06:INFO] [0524/084706.207714:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:47:06:INFO] [0524/084706.210395:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:47:06:INFO] [0524/084706.210905:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:47:06:INFO] [0524/084706.251828:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:47:06:INFO] [0524/084706.252119:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[08:47:06:INFO] [0524/084706.305575:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:47:06:INFO] [0524/084706.358212:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:47:06:INFO] [0524/084706.358356:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[08:47:19:INFO] [0524/084719.012178:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:47:19:INFO] [0524/084719.046354:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:47:19:INFO] [0524/084719.046552:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:47:22:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadOnRemoveAccount]' passed (25.883 seconds).\n[08:47:22:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadSyncOnForgetIdentity]' started.\n[08:47:24:INFO] 2019-05-24 08:47:24.188 ios_chrome_unified_consent_egtests[17210:247228] -- 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:47:26:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadSyncOnForgetIdentity]' passed (3.626 seconds).\n[08:47:26:INFO] Test Case '-[AccountCollectionsTestCase testSignOutOnRemoveAccount]' started.\n[08:47:35:INFO] [0524/084735.660539:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[08:47:35:INFO] [0524/084735.661178:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[08:47:35:INFO] [0524/084735.675006:ERROR:account_tracker.cc(264)] OnOAuthError\n[08:47:35:INFO] [0524/084735.675443:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[08:47:35:INFO] [0524/084735.771220:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[08:47:46:INFO] [0524/084746.273905:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[08:47:46:INFO] [0524/084746.338291:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[08:47:46:INFO] [0524/084746.338565:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[08:47:51:INFO] Test Case '-[AccountCollectionsTestCase testSignOutOnRemoveAccount]' passed (25.892 seconds).\n[08:47:51:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithPassphraseRequired]' started.\n[08:47:53:INFO] [0524/084753.688439:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:47:53:INFO] 2019-05-24 08:47:53.701 ios_chrome_unified_consent_egtests[17210:247228] -- 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:47:55:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithPassphraseRequired]' passed (3.637 seconds).\n[08:47:55:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithSyncingMessage]' started.\n[08:47:57:INFO] 2019-05-24 08:47:57.350 ios_chrome_unified_consent_egtests[17210:247228] -- 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:47:59:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithSyncingMessage]' passed (3.634 seconds).\n[08:47:59:INFO] Test Suite 'AccountCollectionsTestCase' passed at 2019-05-24 08:47:59.206.\n[08:47:59:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 141.987 (143.895) seconds\n[08:47:59:INFO] Test Suite 'BlockPopupsTestCase' started at 2019-05-24 08:47:59.207\n[08:47:59:INFO] [0524/084759.208248:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:47:59:INFO] Test Case '-[BlockPopupsTestCase testAccessibilityOfBlockPopupSettings]' started.\n[08:48:18:INFO] Test Case '-[BlockPopupsTestCase testAccessibilityOfBlockPopupSettings]' passed (19.524 seconds).\n[08:48:18:INFO] Test Case '-[BlockPopupsTestCase testPopupsAllowed]' started.\n[08:48:20:INFO] [0524/084820.786133:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:48:24:INFO] Test Case '-[BlockPopupsTestCase testPopupsAllowed]' passed (6.056 seconds).\n[08:48:24:INFO] Test Case '-[BlockPopupsTestCase testPopupsBlocked]' started.\n[08:48:30:INFO] Test Case '-[BlockPopupsTestCase testPopupsBlocked]' passed (5.306 seconds).\n[08:48:30:INFO] Test Case '-[BlockPopupsTestCase testSettingsPageWithExceptions]' started.\n[08:48:52:INFO] Test Case '-[BlockPopupsTestCase testSettingsPageWithExceptions]' passed (22.581 seconds).\n[08:48:52:INFO] Test Suite 'BlockPopupsTestCase' passed at 2019-05-24 08:48:52.684.\n[08:48:52:INFO] \t Executed 4 tests, with 0 failures (0 unexpected) in 53.467 (53.477) seconds\n[08:48:52:INFO] Test Suite 'BookmarksAccessibilityTestCase' started at 2019-05-24 08:48:52.684\n[08:48:52:INFO] [0524/084852.685853:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:48:52:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' started.\n[08:49:06:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' passed (14.123 seconds).\n[08:49:06:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' started.\n[08:49:23:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' passed (16.373 seconds).\n[08:49:23:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' started.\n[08:49:39:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' passed (16.337 seconds).\n[08:49:39:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' started.\n[08:49:49:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' passed (9.744 seconds).\n[08:49:49:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' started.\n[08:50:05:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' passed (16.347 seconds).\n[08:50:05:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' started.\n[08:50:24:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' passed (19.009 seconds).\n[08:50:24:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' started.\n[08:50:37:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' passed (12.391 seconds).\n[08:50:37:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' started.\n[08:50:48:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' passed (11.948 seconds).\n[08:50:48:INFO] Test Suite 'BookmarksAccessibilityTestCase' passed at 2019-05-24 08:50:48.970.\n[08:50:48:INFO] \t Executed 8 tests, with 0 failures (0 unexpected) in 116.272 (116.286) seconds\n[08:50:48:INFO] Test Suite 'BookmarksEntriesTestCase' started at 2019-05-24 08:50:48.971\n[08:50:48:INFO] [0524/085048.972326:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:50:48:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' started.\n[08:51:02:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' passed (13.847 seconds).\n[08:51:02:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' started.\n[08:51:16:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' passed (14.133 seconds).\n[08:51:16:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' started.\n[08:51:25:INFO] [0524/085125.900967:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[08:51:31:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' passed (14.137 seconds).\n[08:51:31:INFO] Test Case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' started.\n[08:51:42:INFO] 2019-05-24 08:51:42.078 ios_chrome_unified_consent_egtests[17210:247228] Returning local object of class NSString\n[08:51:45:INFO] Test Case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' passed (14.661 seconds).\n[08:51:45:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' started.\n[08:52:01:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' passed (15.531 seconds).\n[08:52:01:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' started.\n[08:52:16:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' passed (15.153 seconds).\n[08:52:16:INFO] Test Case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' started.\n[08:52:43:INFO] Test Case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' passed (27.369 seconds).\n[08:52:43:INFO] Test Case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' started.\n[08:52:57:INFO] Test Case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' passed (14.000 seconds).\n[08:52:57:INFO] Test Case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' started.\n[08:53:16:INFO] Test Case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' passed (18.315 seconds).\n[08:53:16:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' started.\n[08:53:42:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' passed (26.852 seconds).\n[08:53:42:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' started.\n[08:54:06:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed (23.599 seconds).\n[08:54:06:INFO] Test Case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' started.\n[08:54:43:INFO] Test Case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' passed (37.375 seconds).\n[08:54:43:INFO] Test Case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' started.\n[08:54:53:INFO] [0524/085453.394099:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:55:09:INFO] [0524/085509.343435:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /www.a.fr/\n[08:55:31:INFO] [0524/085531.764268:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /www.a.fr/\n[08:55:33:INFO] 2019-05-24 08:55:33.671 ios_chrome_unified_consent_egtests[17210:247228] 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:55:33:INFO] 2019-05-24 08:55:33.671 ios_chrome_unified_consent_egtests[17210:247228] 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:55:33:INFO] 2019-05-24 08:55:33.672 ios_chrome_unified_consent_egtests[17210:247228] 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:55:33:INFO] 2019-05-24 08:55:33.672 ios_chrome_unified_consent_egtests[17210:247228] 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:55:35:INFO] Test Case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' passed (51.628 seconds).\n[08:55:35:INFO] Test Case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' started.\n[08:55:52:INFO] Test Case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' passed (17.149 seconds).\n[08:55:52:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' started.\n[08:56:12:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' passed (19.440 seconds).\n[08:56:12:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' started.\n[08:56:28:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' passed (16.136 seconds).\n[08:56:28:INFO] Test Suite 'BookmarksEntriesTestCase' passed at 2019-05-24 08:56:28.312.\n[08:56:28:INFO] \t Executed 16 tests, with 0 failures (0 unexpected) in 339.324 (339.341) seconds\n[08:56:28:INFO] Test Suite 'BookmarksFoldersTestCase' started at 2019-05-24 08:56:28.313\n[08:56:28:INFO] [0524/085628.314238:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[08:56:28:INFO] Test Case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' started.\n[08:56:30:INFO] [0524/085630.383286:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[08:56:48:INFO] Test Case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' passed (20.420 seconds).\n[08:56:48:INFO] Test Case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' started.\n[08:57:02:INFO] Test Case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' passed (13.609 seconds).\n[08:57:02:INFO] Test Case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' started.\n[08:57:18:INFO] Test Case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' passed (16.557 seconds).\n[08:57:18:INFO] Test Case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' started.\n[08:57:32:INFO] Test Case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' passed (13.873 seconds).\n[08:57:32:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' started.\n[08:57:58:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' passed (26.002 seconds).\n[08:57:58:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' started.\n[08:58:09:INFO] 2019-05-24 08:58:09.280 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[08:58:09:INFO] .\n[08:58:09:INFO] 2019-05-24 08:58:09.286 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Done.\n[08:58:12:INFO] 2019-05-24 08:58:12.917 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[08:58:12:INFO] .\n[08:58:12:INFO] 2019-05-24 08:58:12.922 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Done.\n[08:58:17:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' passed (18.968 seconds).\n[08:58:17:INFO] Test Case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' started.\n[08:58:33:INFO] Test Case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' passed (15.735 seconds).\n[08:58:33:INFO] Test Case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' started.\n[08:59:21:INFO] 2019-05-24 08:59:21.460 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[08:59:21:INFO] .\n[08:59:21:INFO] 2019-05-24 08:59:21.466 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Done.\n[08:59:27:INFO] Test Case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' passed (54.286 seconds).\n[08:59:27:INFO] Test Case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' started.\n[08:59:45:INFO] Test Case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' passed (17.511 seconds).\n[08:59:45:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' started.\n[09:00:11:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' passed (26.112 seconds).\n[09:00:11:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' started.\n[09:00:44:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' passed (32.718 seconds).\n[09:00:44:INFO] Test Case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' started.\n[09:01:16:INFO] Test Case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' passed (32.027 seconds).\n[09:01:16:INFO] Test Case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' started.\n[09:02:02:INFO] Test Case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' passed (46.760 seconds).\n[09:02:02:INFO] Test Case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' started.\n[09:02:35:INFO] Test Case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' passed (32.741 seconds).\n[09:02:35:INFO] Test Case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' started.\n[09:02:58:INFO] Test Case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' passed (22.836 seconds).\n[09:02:58:INFO] Test Suite 'BookmarksFoldersTestCase' passed at 2019-05-24 09:02:58.485.\n[09:02:58:INFO] \t Executed 15 tests, with 0 failures (0 unexpected) in 390.155 (390.172) seconds\n[09:02:58:INFO] Test Suite 'BookmarksPromoTestCase' started at 2019-05-24 09:02:58.485\n[09:02:58:INFO] [0524/090258.486784:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:02:58:INFO] Test Case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' started.\n[09:03:13:INFO] Test Case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' passed (15.039 seconds).\n[09:03:13:INFO] Test Case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' started.\n[09:03:23:INFO] [0524/090323.025706:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[09:03:24:INFO] Test Case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' passed (10.953 seconds).\n[09:03:24:INFO] Test Case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' started.\n[09:03:39:INFO] Test Case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' passed (14.971 seconds).\n[09:03:39:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' started.\n[09:03:51:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed (12.280 seconds).\n[09:03:51:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' started.\n[09:04:05:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed (14.062 seconds).\n[09:04:05:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' started.\n[09:04:22:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed (16.306 seconds).\n[09:04:22:INFO] Test Suite 'BookmarksPromoTestCase' passed at 2019-05-24 09:04:22.109.\n[09:04:22:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 83.611 (83.623) seconds\n[09:04:22:INFO] Test Suite 'BookmarksSearchTestCase' started at 2019-05-24 09:04:22.109\n[09:04:22:INFO] [0524/090422.110879:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:04:22:INFO] Test Case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' started.\n[09:04:35:INFO] Test Case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' passed (13.217 seconds).\n[09:04:35:INFO] Test Case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' started.\n[09:04:45:INFO] 2019-05-24 09:04:45.167 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key U.\n[09:04:45:INFO] 2019-05-24 09:04:45.170 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: U.\n[09:04:45:INFO] 2019-05-24 09:04:45.264 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key R.\n[09:04:45:INFO] 2019-05-24 09:04:45.372 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:04:45:INFO] 2019-05-24 09:04:45.426 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: R.\n[09:04:45:INFO] 2019-05-24 09:04:45.498 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key L.\n[09:04:45:INFO] 2019-05-24 09:04:45.601 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:04:45:INFO] 2019-05-24 09:04:45.653 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: L.\n[09:04:45:INFO] 2019-05-24 09:04:45.722 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:04:45:INFO] .\n[09:04:45:INFO] 2019-05-24 09:04:45.725 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Search.\n[09:05:01:INFO] Test Case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed (26.456 seconds).\n[09:05:01:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' started.\n[09:05:14:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' passed (12.317 seconds).\n[09:05:14:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' started.\n[09:05:24:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' passed (10.087 seconds).\n[09:05:24:INFO] Test Case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' started.\n[09:05:34:INFO] 2019-05-24 09:05:34.034 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key X.\n[09:05:34:INFO] 2019-05-24 09:05:34.040 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: X.\n[09:05:38:INFO] Test Case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' passed (14.499 seconds).\n[09:05:38:INFO] Test Case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' started.\n[09:05:48:INFO] 2019-05-24 09:05:48.525 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:05:48:INFO] 2019-05-24 09:05:48.532 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:05:48:INFO] 2019-05-24 09:05:48.633 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:05:48:INFO] 2019-05-24 09:05:48.633 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:05:48:INFO] 2019-05-24 09:05:48.698 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:05:48:INFO] 2019-05-24 09:05:48.699 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:05:48:INFO] 2019-05-24 09:05:48.763 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key s.\n[09:05:48:INFO] 2019-05-24 09:05:48.764 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: s.\n[09:05:48:INFO] 2019-05-24 09:05:48.826 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key t.\n[09:05:48:INFO] 2019-05-24 09:05:48.827 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: t.\n[09:05:48:INFO] 2019-05-24 09:05:48.883 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:05:48:INFO] .\n[09:05:48:INFO] 2019-05-24 09:05:48.885 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Search.\n[09:05:52:INFO] Test Case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' passed (13.960 seconds).\n[09:05:52:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' started.\n[09:06:02:INFO] 2019-05-24 09:06:02.500 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key U.\n[09:06:02:INFO] 2019-05-24 09:06:02.506 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: U.\n[09:06:02:INFO] 2019-05-24 09:06:02.587 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key R.\n[09:06:02:INFO] 2019-05-24 09:06:02.693 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:06:02:INFO] 2019-05-24 09:06:02.747 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: R.\n[09:06:02:INFO] 2019-05-24 09:06:02.821 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key L.\n[09:06:02:INFO] 2019-05-24 09:06:02.924 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:06:02:INFO] 2019-05-24 09:06:02.977 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: L.\n[09:06:03:INFO] 2019-05-24 09:06:03.045 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:06:03:INFO] .\n[09:06:03:INFO] 2019-05-24 09:06:03.048 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Search.\n[09:06:08:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' passed (16.311 seconds).\n[09:06:08:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' started.\n[09:06:18:INFO] 2019-05-24 09:06:18.803 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:06:18:INFO] 2019-05-24 09:06:18.808 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:06:18:INFO] 2019-05-24 09:06:18.911 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:06:18:INFO] 2019-05-24 09:06:18.912 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:06:18:INFO] 2019-05-24 09:06:18.973 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:06:18:INFO] 2019-05-24 09:06:18.973 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:06:19:INFO] 2019-05-24 09:06:19.035 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key s.\n[09:06:19:INFO] 2019-05-24 09:06:19.036 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: s.\n[09:06:19:INFO] 2019-05-24 09:06:19.096 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key t.\n[09:06:19:INFO] 2019-05-24 09:06:19.097 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: t.\n[09:06:19:INFO] 2019-05-24 09:06:19.152 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:06:19:INFO] .\n[09:06:19:INFO] 2019-05-24 09:06:19.154 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Search.\n[09:06:29:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' passed (20.900 seconds).\n[09:06:29:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' started.\n[09:06:37:INFO] 2019-05-24 09:06:37.484 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:06:37:INFO] 2019-05-24 09:06:37.489 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:06:37:INFO] 2019-05-24 09:06:37.612 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:06:37:INFO] 2019-05-24 09:06:37.613 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:06:37:INFO] 2019-05-24 09:06:37.675 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:06:37:INFO] 2019-05-24 09:06:37.675 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:06:37:INFO] 2019-05-24 09:06:37.734 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key s.\n[09:06:37:INFO] 2019-05-24 09:06:37.735 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: s.\n[09:06:37:INFO] 2019-05-24 09:06:37.792 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key t.\n[09:06:37:INFO] 2019-05-24 09:06:37.793 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: t.\n[09:06:37:INFO] 2019-05-24 09:06:37.849 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:06:37:INFO] .\n[09:06:37:INFO] 2019-05-24 09:06:37.852 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Search.\n[09:06:49:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' passed (19.275 seconds).\n[09:06:49:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' started.\n[09:06:59:INFO] 2019-05-24 09:06:58.997 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:06:59:INFO] 2019-05-24 09:06:59.002 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:06:59:INFO] 2019-05-24 09:06:59.105 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key o.\n[09:06:59:INFO] 2019-05-24 09:06:59.106 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: o.\n[09:06:59:INFO] 2019-05-24 09:06:59.187 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key l.\n[09:06:59:INFO] 2019-05-24 09:06:59.188 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: l.\n[09:06:59:INFO] 2019-05-24 09:06:59.256 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key d.\n[09:06:59:INFO] 2019-05-24 09:06:59.257 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: d.\n[09:06:59:INFO] 2019-05-24 09:06:59.324 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key e.\n[09:06:59:INFO] 2019-05-24 09:06:59.325 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: e.\n[09:06:59:INFO] 2019-05-24 09:06:59.392 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:06:59:INFO] 2019-05-24 09:06:59.393 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:06:59:INFO] 2019-05-24 09:06:59.458 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key .\n[09:06:59:INFO] 2019-05-24 09:06:59.460 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: space.\n[09:06:59:INFO] 2019-05-24 09:06:59.525 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key 1.\n[09:06:59:INFO] 2019-05-24 09:06:59.628 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: more, numbers.\n[09:06:59:INFO] 2019-05-24 09:06:59.697 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: 1.\n[09:07:04:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' passed (15.594 seconds).\n[09:07:04:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolders]' started.\n[09:07:21:INFO] 2019-05-24 09:07:21.304 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:07:21:INFO] 2019-05-24 09:07:21.310 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:07:21:INFO] 2019-05-24 09:07:21.438 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key o.\n[09:07:21:INFO] 2019-05-24 09:07:21.439 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: o.\n[09:07:21:INFO] 2019-05-24 09:07:21.509 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key l.\n[09:07:21:INFO] 2019-05-24 09:07:21.510 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: l.\n[09:07:21:INFO] 2019-05-24 09:07:21.582 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key d.\n[09:07:21:INFO] 2019-05-24 09:07:21.585 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: d.\n[09:07:21:INFO] 2019-05-24 09:07:21.659 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key e.\n[09:07:21:INFO] 2019-05-24 09:07:21.659 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: e.\n[09:07:21:INFO] 2019-05-24 09:07:21.726 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:07:21:INFO] 2019-05-24 09:07:21.726 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:07:21:INFO] 2019-05-24 09:07:21.793 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key .\n[09:07:21:INFO] 2019-05-24 09:07:21.794 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: space.\n[09:07:21:INFO] 2019-05-24 09:07:21.860 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key 1.\n[09:07:21:INFO] 2019-05-24 09:07:21.963 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: more, numbers.\n[09:07:22:INFO] 2019-05-24 09:07:22.014 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: 1.\n[09:07:22:INFO] 2019-05-24 09:07:22.073 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key ..\n[09:07:22:INFO] 2019-05-24 09:07:22.075 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: ..\n[09:07:22:INFO] 2019-05-24 09:07:22.145 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key 1.\n[09:07:22:INFO] 2019-05-24 09:07:22.145 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: 1.\n[09:07:28:INFO] 2019-05-24 09:07:28.190 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:07:28:INFO] 2019-05-24 09:07:28.196 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:07:28:INFO] 2019-05-24 09:07:28.295 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key o.\n[09:07:28:INFO] 2019-05-24 09:07:28.296 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: o.\n[09:07:28:INFO] 2019-05-24 09:07:28.364 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key l.\n[09:07:28:INFO] 2019-05-24 09:07:28.365 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: l.\n[09:07:28:INFO] 2019-05-24 09:07:28.435 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key d.\n[09:07:28:INFO] 2019-05-24 09:07:28.436 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: d.\n[09:07:28:INFO] 2019-05-24 09:07:28.508 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key e.\n[09:07:28:INFO] 2019-05-24 09:07:28.509 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: e.\n[09:07:28:INFO] 2019-05-24 09:07:28.574 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:07:28:INFO] 2019-05-24 09:07:28.575 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:07:28:INFO] 2019-05-24 09:07:28.640 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key .\n[09:07:28:INFO] 2019-05-24 09:07:28.642 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: space.\n[09:07:28:INFO] 2019-05-24 09:07:28.706 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key 2.\n[09:07:28:INFO] 2019-05-24 09:07:28.809 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: more, numbers.\n[09:07:28:INFO] 2019-05-24 09:07:28.861 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: 2.\n[09:07:32:INFO] 2019-05-24 09:07:32.264 ios_chrome_unified_consent_egtests[17210:247228] changing items while animating can result in a corrupted navigation bar\n[09:07:35:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolders]' passed (30.496 seconds).\n[09:07:35:INFO] Test Case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' started.\n[09:07:45:INFO] 2019-05-24 09:07:45.445 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:07:45:INFO] 2019-05-24 09:07:45.446 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:07:45:INFO] 2019-05-24 09:07:45.544 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:07:45:INFO] 2019-05-24 09:07:45.545 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:07:45:INFO] 2019-05-24 09:07:45.608 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:07:45:INFO] 2019-05-24 09:07:45.608 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:07:45:INFO] 2019-05-24 09:07:45.665 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key s.\n[09:07:45:INFO] 2019-05-24 09:07:45.666 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: s.\n[09:07:45:INFO] 2019-05-24 09:07:45.722 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key t.\n[09:07:45:INFO] 2019-05-24 09:07:45.722 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: t.\n[09:07:49:INFO] Test Case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' passed (13.973 seconds).\n[09:07:49:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' started.\n[09:07:59:INFO] 2019-05-24 09:07:59.052 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:07:59:INFO] 2019-05-24 09:07:59.080 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:07:59:INFO] 2019-05-24 09:07:59.182 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key o.\n[09:07:59:INFO] 2019-05-24 09:07:59.183 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: o.\n[09:07:59:INFO] 2019-05-24 09:07:59.263 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key l.\n[09:07:59:INFO] 2019-05-24 09:07:59.265 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: l.\n[09:07:59:INFO] 2019-05-24 09:07:59.332 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key d.\n[09:07:59:INFO] 2019-05-24 09:07:59.333 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: d.\n[09:07:59:INFO] 2019-05-24 09:07:59.401 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key e.\n[09:07:59:INFO] 2019-05-24 09:07:59.401 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: e.\n[09:07:59:INFO] 2019-05-24 09:07:59.470 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:07:59:INFO] 2019-05-24 09:07:59.471 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:07:59:INFO] 2019-05-24 09:07:59.537 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key .\n[09:07:59:INFO] 2019-05-24 09:07:59.539 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: space.\n[09:07:59:INFO] 2019-05-24 09:07:59.602 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key 1.\n[09:07:59:INFO] 2019-05-24 09:07:59.704 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: more, numbers.\n[09:07:59:INFO] 2019-05-24 09:07:59.757 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: 1.\n[09:07:59:INFO] 2019-05-24 09:07:59.816 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key ..\n[09:07:59:INFO] 2019-05-24 09:07:59.818 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: ..\n[09:07:59:INFO] 2019-05-24 09:07:59.882 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key 1.\n[09:07:59:INFO] 2019-05-24 09:07:59.883 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: 1.\n[09:08:10:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' passed (21.225 seconds).\n[09:08:10:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' started.\n[09:08:20:INFO] 2019-05-24 09:08:20.290 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:08:20:INFO] 2019-05-24 09:08:20.323 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:08:20:INFO] 2019-05-24 09:08:20.425 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:08:20:INFO] 2019-05-24 09:08:20.426 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:08:20:INFO] 2019-05-24 09:08:20.503 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:08:20:INFO] 2019-05-24 09:08:20.503 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:08:20:INFO] 2019-05-24 09:08:20.563 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key s.\n[09:08:20:INFO] 2019-05-24 09:08:20.564 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: s.\n[09:08:20:INFO] 2019-05-24 09:08:20.631 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key t.\n[09:08:20:INFO] 2019-05-24 09:08:20.633 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: t.\n[09:08:31:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' passed (20.817 seconds).\n[09:08:31:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' started.\n[09:08:46:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' passed (15.231 seconds).\n[09:08:46:INFO] Test Case '-[BookmarksSearchTestCase testSearchResults]' started.\n[09:08:56:INFO] 2019-05-24 09:08:56.629 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key o.\n[09:08:56:INFO] 2019-05-24 09:08:56.834 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:08:56:INFO] 2019-05-24 09:08:56.895 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: o.\n[09:08:57:INFO] 2019-05-24 09:08:57.719 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key n.\n[09:08:57:INFO] 2019-05-24 09:08:57.721 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: n.\n[09:08:58:INFO] 2019-05-24 09:08:58.928 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key y.\n[09:08:58:INFO] 2019-05-24 09:08:58.928 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: y.\n[09:09:03:INFO] Test Case '-[BookmarksSearchTestCase testSearchResults]' passed (16.520 seconds).\n[09:09:03:INFO] Test Case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' started.\n[09:09:13:INFO] 2019-05-24 09:09:13.229 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:09:13:INFO] 2019-05-24 09:09:13.435 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:09:13:INFO] 2019-05-24 09:09:13.494 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:09:13:INFO] 2019-05-24 09:09:13.926 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key \b.\n[09:09:13:INFO] 2019-05-24 09:09:13.927 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: delete.\n[09:09:18:INFO] Test Case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' passed (15.386 seconds).\n[09:09:18:INFO] Test Case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' started.\n[09:09:32:INFO] Test Case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' passed (14.472 seconds).\n[09:09:32:INFO] Test Case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' started.\n[09:09:42:INFO] 2019-05-24 09:09:42.718 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key F.\n[09:09:42:INFO] 2019-05-24 09:09:42.748 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: F.\n[09:09:42:INFO] 2019-05-24 09:09:42.856 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key i.\n[09:09:42:INFO] 2019-05-24 09:09:42.856 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: i.\n[09:09:42:INFO] 2019-05-24 09:09:42.925 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key r.\n[09:09:42:INFO] 2019-05-24 09:09:42.925 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: r.\n[09:09:42:INFO] 2019-05-24 09:09:42.982 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key s.\n[09:09:42:INFO] 2019-05-24 09:09:42.983 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: s.\n[09:09:43:INFO] 2019-05-24 09:09:43.044 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key t.\n[09:09:43:INFO] 2019-05-24 09:09:43.044 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: t.\n[09:09:43:INFO] 2019-05-24 09:09:43.100 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key .\n[09:09:43:INFO] 2019-05-24 09:09:43.102 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: space.\n[09:09:43:INFO] 2019-05-24 09:09:43.156 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key U.\n[09:09:43:INFO] 2019-05-24 09:09:43.259 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:09:43:INFO] 2019-05-24 09:09:43.311 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: U.\n[09:09:43:INFO] 2019-05-24 09:09:43.375 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key R.\n[09:09:43:INFO] 2019-05-24 09:09:43.476 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:09:43:INFO] 2019-05-24 09:09:43.527 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: R.\n[09:09:43:INFO] 2019-05-24 09:09:43.592 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key L.\n[09:09:43:INFO] 2019-05-24 09:09:43.695 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:09:43:INFO] 2019-05-24 09:09:43.748 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: L.\n[09:09:48:INFO] Test Case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' passed (15.932 seconds).\n[09:09:48:INFO] Test Case '-[BookmarksSearchTestCase testSearchWithNoResults]' started.\n[09:09:58:INFO] 2019-05-24 09:09:58.656 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key z.\n[09:09:58:INFO] 2019-05-24 09:09:58.861 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: shift.\n[09:09:58:INFO] 2019-05-24 09:09:58.921 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: z.\n[09:09:58:INFO] 2019-05-24 09:09:58.991 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key z.\n[09:09:58:INFO] 2019-05-24 09:09:58.992 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: z.\n[09:09:59:INFO] 2019-05-24 09:09:59.046 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:09:59:INFO] .\n[09:09:59:INFO] 2019-05-24 09:09:59.047 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Search.\n[09:10:02:INFO] Test Case '-[BookmarksSearchTestCase testSearchWithNoResults]' passed (14.067 seconds).\n[09:10:02:INFO] Test Suite 'BookmarksSearchTestCase' passed at 2019-05-24 09:10:02.864.\n[09:10:02:INFO] \t Executed 20 tests, with 0 failures (0 unexpected) in 340.734 (340.755) seconds\n[09:10:02:INFO] Test Suite 'BookmarksTestCase' started at 2019-05-24 09:10:02.865\n[09:10:02:INFO] [0524/091002.866796:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:10:02:INFO] Test Case '-[BookmarksTestCase testAddRemoveBookmark]' started.\n[09:10:05:INFO] [0524/091005.007340:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:10:37:INFO] Test Case '-[BookmarksTestCase testAddRemoveBookmark]' passed (34.906 seconds).\n[09:10:37:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' started.\n[09:10:53:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' passed (15.784 seconds).\n[09:10:53:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' started.\n[09:11:09:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' passed (15.683 seconds).\n[09:11:09:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarShown]' started.\n[09:11:21:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarShown]' passed (12.300 seconds).\n[09:11:21:INFO] Test Case '-[BookmarksTestCase testBookmarkMultipleTabs]' started.\n[09:11:38:INFO] Test Case '-[BookmarksTestCase testBookmarkMultipleTabs]' passed (16.726 seconds).\n[09:11:38:INFO] Test Case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' started.\n[09:11:54:INFO] 2019-05-24 09:11:54.909 ios_chrome_unified_consent_egtests[17210:247228] Attempting to type key\n[09:11:54:INFO] .\n[09:11:54:INFO] 2019-05-24 09:11:54.916 ios_chrome_unified_consent_egtests[17210:247228] Tapping on key: Done.\n[09:12:00:INFO] Test Case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' passed (22.493 seconds).\n[09:12:00:INFO] Test Case '-[BookmarksTestCase testBookmarksURLDisabled]' started.\n[09:12:05:INFO] Test Case '-[BookmarksTestCase testBookmarksURLDisabled]' passed (5.035 seconds).\n[09:12:05:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreated]' started.\n[09:12:37:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreated]' passed (31.522 seconds).\n[09:12:37:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' started.\n[09:13:04:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' passed (26.977 seconds).\n[09:13:04:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' started.\n[09:13:26:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' passed (22.596 seconds).\n[09:13:26:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' started.\n[09:13:45:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' passed (18.157 seconds).\n[09:13:45:INFO] Test Case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' started.\n[09:14:08:INFO] Test Case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' passed (23.093 seconds).\n[09:14:08:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' started.\n[09:14:28:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' passed (20.129 seconds).\n[09:14:28:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' started.\n[09:14:35:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' passed (7.692 seconds).\n[09:14:35:INFO] Test Case '-[BookmarksTestCase testMoveDoesSaveOnSave]' started.\n[09:15:02:INFO] Test Case '-[BookmarksTestCase testMoveDoesSaveOnSave]' passed (26.647 seconds).\n[09:15:02:INFO] Test Case '-[BookmarksTestCase testNavigateBackWithGesture]' started.\n[09:15:20:INFO] Test Case '-[BookmarksTestCase testNavigateBackWithGesture]' passed (18.285 seconds).\n[09:15:20:INFO] Test Suite 'BookmarksTestCase' passed at 2019-05-24 09:15:20.909.\n[09:15:20:INFO] \t Executed 16 tests, with 0 failures (0 unexpected) in 318.025 (318.043) seconds\n[09:15:20:INFO] Test Suite 'FirstRunTestCase' started at 2019-05-24 09:15:20.909\n[09:15:20:INFO] [0524/091520.910905:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:15:20:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' started.\n[09:15:28:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' passed (7.204 seconds).\n[09:15:28:INFO] Test Case '-[FirstRunTestCase testPrivacy]' started.\n[09:15:35:INFO] Test Case '-[FirstRunTestCase testPrivacy]' passed (7.193 seconds).\n[09:15:35:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' started.\n[09:15:39:INFO] [0524/091539.588319:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:15:39:INFO] [0524/091539.590964:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:15:39:INFO] [0524/091539.629423:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:15:39:INFO] [0524/091539.629768:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:15:39:INFO] [0524/091539.682783:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:15:39:INFO] [0524/091539.682971:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:15:42:INFO] [0524/091542.844734:INFO:profile_sync_service.cc(1746)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:15:44:INFO] [0524/091544.074909:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:15:44:INFO] [0524/091544.113059:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:15:44:INFO] [0524/091544.113277:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:15:44:INFO] [0524/091544.580898:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12\n[09:15:46:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' passed (10.847 seconds).\n[09:15:46:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' started.\n[09:15:47:INFO] [0524/091547.982660:WARNING:first_run_egtest.mm(203)] Skipping test as there is no undo operation when Unified Consent is enabled.\n[09:15:49:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' passed (3.712 seconds).\n[09:15:49:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' started.\n[09:15:57:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' passed (7.150 seconds).\n[09:15:57:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' started.\n[09:16:03:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' passed (6.530 seconds).\n[09:16:03:INFO] Test Suite 'FirstRunTestCase' passed at 2019-05-24 09:16:03.558.\n[09:16:03:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 42.636 (42.648) seconds\n[09:16:03:INFO] Test Suite 'GoogleServicesSettingsTestCase' started at 2019-05-24 09:16:03.559\n[09:16:03:INFO] [0524/091603.560050:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:16:03:INFO] Test Case '-[GoogleServicesSettingsTestCase testInterruptSigninFromGoogleServicesSettings]' started.\n[09:16:13:INFO] [0524/091613.697265:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:16:13:INFO] [0524/091613.698981:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:16:13:INFO] [0524/091613.711987:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:16:13:INFO] [0524/091613.712362:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:16:13:INFO] [0524/091613.885752:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:16:13:INFO] [0524/091613.885892:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:16:29:INFO] [0524/091629.237988:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:16:29:INFO] [0524/091629.348424:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:16:29:INFO] [0524/091629.348915:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:16:32:INFO] Test Case '-[GoogleServicesSettingsTestCase testInterruptSigninFromGoogleServicesSettings]' passed (28.822 seconds).\n[09:16:32:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpenGoogleServicesSettings]' started.\n[09:16:44:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpenGoogleServicesSettings]' passed (12.242 seconds).\n[09:16:44:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpeningServices]' started.\n[09:16:56:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpeningServices]' passed (11.925 seconds).\n[09:16:56:INFO] Test Suite 'GoogleServicesSettingsTestCase' passed at 2019-05-24 09:16:56.562.\n[09:16:56:INFO] \t Executed 3 tests, with 0 failures (0 unexpected) in 52.989 (53.003) seconds\n[09:16:56:INFO] Test Suite 'SettingsTestCase' started at 2019-05-24 09:16:56.562\n[09:16:56:INFO] [0524/091656.563852:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:16:56:INFO] Test Case '-[SettingsTestCase testAccessibilityOnAddresses]' started.\n[09:17:10:INFO] Test Case '-[SettingsTestCase testAccessibilityOnAddresses]' passed (13.995 seconds).\n[09:17:10:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthManagementSettingsPage]' started.\n[09:17:26:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthManagementSettingsPage]' passed (15.612 seconds).\n[09:17:26:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthPreloadWebpagesSettingsPage]' started.\n[09:17:43:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthPreloadWebpagesSettingsPage]' passed (17.759 seconds).\n[09:17:43:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsBlockPopupsPage]' started.\n[09:18:01:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsBlockPopupsPage]' passed (17.748 seconds).\n[09:18:01:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsPage]' started.\n[09:18:17:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsPage]' passed (15.614 seconds).\n[09:18:17:INFO] Test Case '-[SettingsTestCase testAccessibilityOnGoogleChrome]' started.\n[09:18:34:INFO] Test Case '-[SettingsTestCase testAccessibilityOnGoogleChrome]' passed (17.281 seconds).\n[09:18:34:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPaymentMethods]' started.\n[09:18:48:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPaymentMethods]' passed (13.958 seconds).\n[09:18:48:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyClearBrowsingHistoryPage]' started.\n[09:19:04:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyClearBrowsingHistoryPage]' passed (16.266 seconds).\n[09:19:04:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyHandoffSettingsPage]' started.\n[09:19:20:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyHandoffSettingsPage]' passed (16.113 seconds).\n[09:19:20:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacySettingsPage]' started.\n[09:19:34:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacySettingsPage]' passed (13.949 seconds).\n[09:19:34:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSearchEngine]' started.\n[09:19:48:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSearchEngine]' passed (13.974 seconds).\n[09:19:48:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSendUsageData]' started.\n[09:19:50:INFO] 2019-05-24 09:19:50.676 ios_chrome_unified_consent_egtests[17210:247228] -- 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:19:52:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSendUsageData]' passed (3.793 seconds).\n[09:19:52:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSettingsPage]' started.\n[09:20:02:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSettingsPage]' passed (9.639 seconds).\n[09:20:02:INFO] Test Case '-[SettingsTestCase testAccessibilityOnVoiceSearch]' started.\n[09:20:16:INFO] Test Case '-[SettingsTestCase testAccessibilityOnVoiceSearch]' passed (13.964 seconds).\n[09:20:16:INFO] Test Case '-[SettingsTestCase testBreakpadReporting]' started.\n[09:20:20:INFO] Test Case '-[SettingsTestCase testBreakpadReporting]' passed (3.795 seconds).\n[09:20:20:INFO] Test Case '-[SettingsTestCase testBreakpadReportingFirstLaunch]' started.\n[09:20:23:INFO] Test Case '-[SettingsTestCase testBreakpadReportingFirstLaunch]' passed (3.773 seconds).\n[09:20:23:INFO] Test Case '-[SettingsTestCase testClearCookies]' started.\n[09:20:25:INFO] [0524/092025.896495:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[09:20:46:INFO] Test Case '-[SettingsTestCase testClearCookies]' passed (22.214 seconds).\n[09:20:46:INFO] Test Case '-[SettingsTestCase testMetricsReporting]' started.\n[09:20:49:INFO] Test Case '-[SettingsTestCase testMetricsReporting]' passed (3.790 seconds).\n[09:20:49:INFO] Test Case '-[SettingsTestCase testSettingsKeyboardCommands]' started.\n[09:21:02:INFO] Test Case '-[SettingsTestCase testSettingsKeyboardCommands]' passed (12.567 seconds).\n[09:21:02:INFO] Test Case '-[SettingsTestCase testSettingsSignedOutIncognito]' started.\n[09:21:11:INFO] 2019-05-24 09:21:11.616 ios_chrome_unified_consent_egtests[17210:247228] 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:21:11:INFO] 2019-05-24 09:21:11.617 ios_chrome_unified_consent_egtests[17210:247228] 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:21:11:INFO] 2019-05-24 09:21:11.617 ios_chrome_unified_consent_egtests[17210:247228] 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:21:11:INFO] 2019-05-24 09:21:11.617 ios_chrome_unified_consent_egtests[17210:247228] 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:21:13:INFO] Test Case '-[SettingsTestCase testSettingsSignedOutIncognito]' passed (11.237 seconds).\n[09:21:13:INFO] Test Suite 'SettingsTestCase' passed at 2019-05-24 09:21:13.629.\n[09:21:13:INFO] \t Executed 20 tests, with 0 failures (0 unexpected) in 257.040 (257.067) seconds\n[09:21:13:INFO] Test Suite 'SigninInteractionControllerTestCase' started at 2019-05-24 09:21:13.630\n[09:21:13:INFO] [0524/092113.631549:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:21:13:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' started.\n[09:21:22:INFO] [0524/092122.799201:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:21:22:INFO] [0524/092122.801743:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:21:22:INFO] [0524/092122.877371:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:21:22:INFO] [0524/092122.877536:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:21:22:INFO] [0524/092122.985607:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:21:22:INFO] [0524/092122.986738:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:21:23:INFO] [0524/092123.176421:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:21:23:INFO] [0524/092123.176632:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[09:21:23:INFO] [0524/092123.420105:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:21:23:INFO] [0524/092123.420240:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[09:21:34:INFO] [0524/092134.302991:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:21:34:INFO] [0524/092134.346169:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:21:34:INFO] [0524/092134.346362:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:21:53:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' passed (40.123 seconds).\n[09:21:53:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' started.\n[09:22:17:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' passed (23.568 seconds).\n[09:22:17:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' started.\n[09:22:24:INFO] 2019-05-24 09:22:24.246 ios_chrome_unified_consent_egtests[17210:247228] Warning: Attempt to present <IdentityChooserViewController: 0x7fc2f55f1cb0> on <UnifiedConsentViewController: 0x7fc2f9954ac0> whose view is not in the window hierarchy!\n[09:22:38:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' passed (21.190 seconds).\n[09:22:38:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' started.\n[09:22:48:INFO] [0524/092248.412815:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:22:48:INFO] [0524/092248.413520:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:22:48:INFO] [0524/092248.439162:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:22:48:INFO] [0524/092248.439355:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:22:48:INFO] [0524/092248.538848:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:22:59:INFO] [0524/092259.764257:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:22:59:INFO] [0524/092259.804009:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:22:59:INFO] [0524/092259.804204:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:23:05:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' passed (26.998 seconds).\n[09:23:05:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' started.\n[09:23:15:INFO] [0524/092315.323220:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:23:15:INFO] [0524/092315.323382:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:23:15:INFO] [0524/092315.377601:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:23:15:INFO] [0524/092315.377880:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:23:15:INFO] [0524/092315.468834:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:23:19:INFO] [0524/092319.134358:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:23:19:INFO] [0524/092319.169829:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:23:19:INFO] [0524/092319.170027:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:23:21:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' passed (15.686 seconds).\n[09:23:21:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' started.\n[09:23:31:INFO] [0524/092331.214847:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:23:31:INFO] [0524/092331.215044:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:23:31:INFO] [0524/092331.258594:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:23:31:INFO] [0524/092331.258929:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:23:34:INFO] [0524/092334.452361:INFO:profile_sync_service.cc(1746)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[09:23:34:INFO] [0524/092334.675983:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:23:36:INFO] [0524/092336.029922:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:23:36:INFO] [0524/092336.157121:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:23:36:INFO] [0524/092336.157273:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:23:39:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' passed (17.921 seconds).\n[09:23:39:INFO] Test Suite 'SigninInteractionControllerTestCase' passed at 2019-05-24 09:23:39.139.\n[09:23:39:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 145.486 (145.509) seconds\n[09:23:39:INFO] Test Suite 'SigninInteractionControllerUnityEnabledTestCase' started at 2019-05-24 09:23:39.140\n[09:23:39:INFO] [0524/092339.141141:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:23:39:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testAddAccountAutomatically]' started.\n[09:23:50:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testAddAccountAutomatically]' passed (11.276 seconds).\n[09:23:50:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testRemoveLastAccount]' started.\n[09:24:02:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testRemoveLastAccount]' passed (12.164 seconds).\n[09:24:02:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testSignInSwitchAccountsAndImportData]' started.\n[09:24:12:INFO] [0524/092412.086155:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:24:12:INFO] [0524/092412.086666:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:24:12:INFO] [0524/092412.086942:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:24:12:INFO] [0524/092412.088212:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:24:12:INFO] [0524/092412.149532:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:24:12:INFO] [0524/092412.149964:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:24:12:INFO] [0524/092412.167555:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:24:12:INFO] [0524/092412.167744:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[09:24:12:INFO] [0524/092412.196332:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:24:12:INFO] [0524/092412.196665:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[09:24:23:INFO] [0524/092423.538107:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:24:23:INFO] [0524/092423.580099:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:24:23:INFO] [0524/092423.580297:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:24:32:INFO] [0524/092432.594182:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:24:32:INFO] [0524/092432.594691:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:24:32:INFO] [0524/092432.597118:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:24:32:INFO] [0524/092432.597269:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:24:32:INFO] [0524/092432.636535:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:24:32:INFO] [0524/092432.637029:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID\n[09:24:32:INFO] [0524/092432.708741:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:24:32:INFO] [0524/092432.708910:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID\n[09:24:32:INFO] [0524/092432.721261:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:24:35:INFO] [0524/092435.123485:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:24:35:INFO] [0524/092435.151160:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:24:35:INFO] [0524/092435.151423:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:24:38:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testSignInSwitchAccountsAndImportData]' passed (35.975 seconds).\n[09:24:38:INFO] Test Suite 'SigninInteractionControllerUnityEnabledTestCase' passed at 2019-05-24 09:24:38.566.\n[09:24:38:INFO] \t Executed 3 tests, with 0 failures (0 unexpected) in 59.414 (59.426) seconds\n[09:24:38:INFO] Test Suite 'SigninSettingsTestCase' started at 2019-05-24 09:24:38.566\n[09:24:38:INFO] [0524/092438.567861:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:24:38:INFO] Test Case '-[SigninSettingsTestCase testAutomaticSigninPromoDismiss]' started.\n[09:24:53:INFO] Test Case '-[SigninSettingsTestCase testAutomaticSigninPromoDismiss]' passed (15.268 seconds).\n[09:24:53:INFO] Test Case '-[SigninSettingsTestCase testDissmissSigninPromo]' started.\n[09:25:03:INFO] Test Case '-[SigninSettingsTestCase testDissmissSigninPromo]' passed (10.033 seconds).\n[09:25:03:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithColdStateUsingPrimaryButton]' started.\n[09:25:16:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed (12.310 seconds).\n[09:25:16:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' started.\n[09:25:23:INFO] [0524/092523.737844:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:25:23:INFO] [0524/092523.741270:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag.\n[09:25:23:INFO] [0524/092523.803042:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:25:23:INFO] [0524/092523.803340:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:25:23:INFO] [0524/092523.843206:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:25:23:INFO] [0524/092523.843458:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:25:25:INFO] [0524/092525.951401:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:25:26:INFO] [0524/092526.066632:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:25:26:INFO] [0524/092526.066788:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:25:29:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed (12.877 seconds).\n[09:25:29:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' started.\n[09:25:30:INFO] [0524/092530.487451:WARNING:gaia_auth_fetcher.cc(894)] ClientLogin failed with\n[09:25:30:INFO] [0524/092530.487571:WARNING:gaia_auth_fetcher.cc(917)] Incomprehensible response from Google Accounts servers.\n[09:25:39:INFO] [0524/092539.303757:ERROR:account_info_fetcher.cc(75)] OnOAuthError\n[09:25:39:INFO] [0524/092539.305007:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID\n[09:25:39:INFO] [0524/092539.310530:ERROR:account_tracker.cc(264)] OnOAuthError\n[09:25:39:INFO] [0524/092539.311019:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[09:25:39:INFO] [0524/092539.433130:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session.\n[09:25:41:INFO] [0524/092541.467534:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[09:25:41:INFO] [0524/092541.582640:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client\n[09:25:41:INFO] [0524/092541.582800:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client\n[09:25:44:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed (15.509 seconds).\n[09:25:44:INFO] Test Suite 'SigninSettingsTestCase' passed at 2019-05-24 09:25:44.576.\n[09:25:44:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 65.996 (66.009) seconds\n[09:25:44:INFO] Test Suite 'TranslateUITestCase' started at 2019-05-24 09:25:44.576\n[09:25:44:INFO] [0524/092544.577877:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist.\n[09:25:44:INFO] Test Case '-[TranslateUITestCase testAccessibilityOfTranslateSettings]' started.\n[09:26:04:INFO] Test Case '-[TranslateUITestCase testAccessibilityOfTranslateSettings]' passed (19.920 seconds).\n[09:26:04:INFO] Test Suite 'TranslateUITestCase' passed at 2019-05-24 09:26:04.507.\n[09:26:04:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 19.920 (19.931) seconds\n[09:26:04:INFO] Test Suite 'ios_chrome_unified_consent_egtests.app' passed at 2019-05-24 09:26:04.508.\n[09:26:04:INFO] \t Executed 138 tests, with 0 failures (0 unexpected) in 2427.057 (2429.198) seconds\n[09:26:04:INFO] Test Suite 'All tests' passed at 2019-05-24 09:26:04.509.\n[09:26:04:INFO] \t Executed 138 tests, with 0 failures (0 unexpected) in 2427.057 (2429.200) seconds\n[09:26:05:INFO] \n[09:26:05:INFO] \n[09:26:05:INFO] Test session results and logs:\n[09:26:05:INFO] \t/Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-afyckwohwxrgovbiqspqoddqylrs/Logs/Test/Test-Transient Testing-2019.05.24_08-44-58--0700.xcresult\n[09:26:05:INFO] \n[09:26:05:INFO] 2019-05-24 09:26:05.009 xcodebuild[17087:245963] [MT] IDETestOperationsObserverDebug: 2466.437 elapsed -- Testing started completed.\n[09:26:05:INFO] 2019-05-24 09:26:05.009 xcodebuild[17087:245963] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[09:26:05:INFO] 2019-05-24 09:26:05.009 xcodebuild[17087:245963] [MT] IDETestOperationsObserverDebug: 2466.437 sec, +2466.437 sec -- end\n[09:26:05:INFO] ** TEST EXECUTE SUCCEEDED **\n[09:26:05:INFO] \n[09:26:09:DEBUG] Finished print_process_output.\n[09:26:09:INFO] Waiting for test process to terminate.\n[09:26:09:INFO] Test process terminated.\n[09:26:09:DEBUG] Setting sigterm handler.\n[09:26:09:DEBUG] Stdout flushed after test process.\n[09:26:09:DEBUG] Processing test results.\n[09:26:09:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0\n\n[09:26:09:DEBUG] Extracting test data.\n[09:26:09:DEBUG] Retrieving crash reports.\n[09:26:09:DEBUG] Retrieving derived data.\n[09:26:09:DEBUG] Making desktop screenshots.\n[09:26:09:DEBUG] Killing simulators.\n[09:26:09:INFO] Killing simulators.\n[09:26:15:DEBUG] Wiping simulator.\nDevice wiped.\n[09:26:16:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "4fa409d22a46766737a779cd6eb1aed84cf2bce0", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "4503555c13a32d11", "server_versions": [ "4303-bae210e" ], "started_ts": "2019-05-24T15:44:29.636893", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios-simulator-full-configs", "buildnumber:14972", "data:a502073ded5d5fed08e1044331f313254c85511d", "device_type:iPhone 7", "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 7 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": "4503555c13a32d10", "try_number": "1" } ] }