Back to build
Raw log | Switch to full mode | Login

Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-05-24 09:27:51,478 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/15r3TsDz' 2019-05-24 09:27:51,479 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/15r3TsDz' already exists! 2019-05-24 09:27:51,479 - root: [WARNING] task_output_dir existing content: [] 2019-05-24 09:27:51,479 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpSlmVZ7.json -output-dir /b/s/w/ir/k/recipe_cleanup/15r3TsDz -task-summary-json /b/s/w/ir/tmp/t/tmpU5FWXN.json [D2019-05-24T09:27:51.506939-07:00 97781 0 auth.go:1265] Minting a new token {"key":"luci_ctx/8f981e636f807859abe6bfdb379bc1f944d42fb6cfa23aa88e210026aaeea944", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-24T09:27:51.507059-07:00 97781 0 luci_ctx.go:138] POST http://127.0.0.1:56627/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/8f981e636f807859abe6bfdb379bc1f944d42fb6cfa23aa88e210026aaeea944", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-24T09:27:51.509269-07:00 97781 0 auth.go:1222] Token expires in 22m12.490748s {"key":"luci_ctx/8f981e636f807859abe6bfdb379bc1f944d42fb6cfa23aa88e210026aaeea944", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 450355aaf136fc10: exit 0 [I 2019-05-24 08:45:09] About to install Xcode 10b61 in Xcode.app for ios [08:45:10:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [08:45:10:INFO] Killing simulators. Device wiped. [08:45:19:DEBUG] Setting sigterm handler. [08:45:21:INFO] User defaults from command line: [08:45:21:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/F6C5867F-4913-4218-9E97-0DC3F817EEB6 [08:45:21:INFO] [08:45:21:INFO] Testing started on 'iPhone 7' [08:45:21:INFO] 2019-05-24 08:45:21.312 xcodebuild[26924:431692] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [08:45:21:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-ahknwillfnssgtasoakegvpjogau/Logs/Test/Test-Transient Testing-2019.05.24_08-45-21--0700.xcresult/1_Test/Diagnostics/ios_chrome_unified_consent_egtests_module-EA45E735-538D-4B71-A4E7-EB285007A33D/ios_chrome_unified_consent_egtests_module-709EDF39-3273-4ED8-8707-17EE84D453D5/Session-ios_chrome_unified_consent_egtests_module-2019-05-24_084521-rhTV9u.log [08:45:21:INFO] 2019-05-24 08:45:21.313 xcodebuild[26924:431674] [MT] IDETestOperationsObserverDebug: (3613C468-8179-4B49-BB62-1A408F5BFA58) Beginning test session ios_chrome_unified_consent_egtests_module-3613C468-8179-4B49-BB62-1A408F5BFA58 at 2019-05-24 08:45:21.313 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f86dc08cb20> { [08:45:21:INFO] SimDevice: iPhone 7 (0794C02B-C743-4213-830F-370925026233, iOS 12.1, Shutdown) [08:45:21:INFO] } (12.1 (16B91)) [08:45:41:INFO] 2019-05-24 08:45:41.789 ios_chrome_unified_consent_egtests[27037:432665] Crash handler setup started. [08:45:42:INFO] 2019-05-24 08:45:41.790 ios_chrome_unified_consent_egtests[27037:432665] Crash handler setup completed. [08:45:42:INFO] 2019-05-24 08:45:41.790 ios_chrome_unified_consent_egtests[27037:432665] Enabling accessibility for automation on Simulator. [08:45:43:INFO] 2019-05-24 08:45:43.606 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:45:43:INFO] 2019-05-24 08:45:43.607 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:45:43:INFO] 2019-05-24 08:45:43.607 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:45:43:INFO] 2019-05-24 08:45:43.607 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:45:45:INFO] 2019-05-24 08:45:45.106 ios_chrome_unified_consent_egtests[27037:432665] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7ff37487f200>. [08:45:45:INFO] [0524/084545.830049: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> &) [08:45:48:INFO] [0524/084548.029845:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/0794C02B-C743-4213-830F-370925026233/data/Containers/Data/Application/8113ED27-2A88-4CF8-8EF1-B9BCA0B98F7F/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/0794C02B-C743-4213-830F-370925026233/data/Containers/Data/Application/8113ED27-2A88-4CF8-8EF1-B9BCA0B98F7F/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [08:45:51:INFO] Test Suite 'All tests' started at 2019-05-24 08:45:51.490 [08:45:51:INFO] Test Suite 'ios_chrome_unified_consent_egtests.app' started at 2019-05-24 08:45:51.491 [08:45:51:INFO] Test Suite 'AccountCollectionsTestCase' started at 2019-05-24 08:45:51.491 [08:45:51:INFO] [0524/084551.500487:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [08:45:53:INFO] Test Case '-[AccountCollectionsTestCase testMDMError]' started. [08:45:55:INFO] 2019-05-24 08:45:55.905 ios_chrome_unified_consent_egtests[27037:432665] -- 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. [08:45:58:INFO] Test Case '-[AccountCollectionsTestCase testMDMError]' passed (4.243 seconds). [08:45:58:INFO] Test Case '-[AccountCollectionsTestCase testSignInDisconnectCancelled]' started. [08:46:08:INFO] 2019-05-24 08:46:08.733 ios_chrome_unified_consent_egtests[27037:432665] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7ff3736e1eb0; baseClass = UIButton; frame = (16 615; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x6000034aa780>>, Touch Target: {86, 36} [08:46:09:INFO] [0524/084609.347727:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [08:46:09:INFO] [0524/084609.378617:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [08:46:09:INFO] [0524/084609.378518:ERROR:account_info_fetcher.cc(75)] OnOAuthError [08:46:09:INFO] [0524/084609.380785:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [08:46:09:INFO] [0524/084609.476705:ERROR:account_tracker.cc(264)] OnOAuthError [08:46:09:INFO] [0524/084609.476854:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [08:46:21:INFO] [0524/084621.152961:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [08:46:21:INFO] [0524/084621.174044:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [08:46:21:INFO] [0524/084621.177453:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [08:46:21:INFO] [0524/084621.486859:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:46:22:INFO] [0524/084622.658101:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:46:24:INFO] Test Case '-[AccountCollectionsTestCase testSignInDisconnectCancelled]' passed (26.490 seconds). [08:46:24:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnDisconnectAccount]' started. [08:46:25:INFO] [0524/084625.537175:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:46:33:INFO] [0524/084633.535840:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:46:34:INFO] [0524/084634.301491:ERROR:account_info_fetcher.cc(75)] OnOAuthError [08:46:34:INFO] [0524/084634.301669:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [08:46:34:INFO] [0524/084634.363907:ERROR:account_tracker.cc(264)] OnOAuthError [08:46:34:INFO] [0524/084634.364130:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [08:46:34:INFO] [0524/084634.400109:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [08:46:44:INFO] [0524/084644.935577:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [08:46:45:INFO] [0524/084644.999491:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [08:46:45:INFO] [0524/084644.999654:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [08:46:50:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnDisconnectAccount]' passed (25.505 seconds). [08:46:50:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnSyncSettings]' started. [08:46:56:INFO] [0524/084656.531729:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:46:59:INFO] [0524/084659.718137:ERROR:account_info_fetcher.cc(75)] OnOAuthError [08:46:59:INFO] [0524/084659.718276:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [08:46:59:INFO] [0524/084659.755336:ERROR:account_tracker.cc(264)] OnOAuthError [08:46:59:INFO] [0524/084659.756556:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [08:46:59:INFO] [0524/084659.831805:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [08:47:09:INFO] [0524/084709.622924:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [08:47:09:INFO] [0524/084709.676410:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [08:47:09:INFO] [0524/084709.676572:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [08:47:14:INFO] Test Case '-[AccountCollectionsTestCase testSignInPopUpAccountOnSyncSettings]' passed (24.654 seconds). [08:47:14:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadOnRemoveAccount]' started. [08:47:24:INFO] [0524/084724.359656:ERROR:account_info_fetcher.cc(75)] OnOAuthError [08:47:24:INFO] [0524/084724.359796:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [08:47:24:INFO] [0524/084724.397149:ERROR:account_tracker.cc(264)] OnOAuthError [08:47:24:INFO] [0524/084724.397326:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [08:47:24:INFO] [0524/084724.463313:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [08:47:24:INFO] [0524/084724.506346:ERROR:account_info_fetcher.cc(75)] OnOAuthError [08:47:24:INFO] [0524/084724.506468:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID [08:47:24:INFO] [0524/084724.613426:ERROR:account_tracker.cc(264)] OnOAuthError [08:47:24:INFO] [0524/084724.613601:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [08:47:37:INFO] [0524/084737.098307:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [08:47:37:INFO] [0524/084737.139114:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [08:47:37:INFO] [0524/084737.139271:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [08:47:40:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadOnRemoveAccount]' passed (25.790 seconds). [08:47:40:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadSyncOnForgetIdentity]' started. [08:47:42:INFO] 2019-05-24 08:47:42.289 ios_chrome_unified_consent_egtests[27037:432665] -- 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. [08:47:44:INFO] Test Case '-[AccountCollectionsTestCase testSignInReloadSyncOnForgetIdentity]' passed (3.620 seconds). [08:47:44:INFO] Test Case '-[AccountCollectionsTestCase testSignOutOnRemoveAccount]' started. [08:47:53:INFO] [0524/084753.725254:ERROR:account_info_fetcher.cc(75)] OnOAuthError [08:47:53:INFO] [0524/084753.725387:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [08:47:53:INFO] [0524/084753.782358:ERROR:account_tracker.cc(264)] OnOAuthError [08:47:53:INFO] [0524/084753.782522:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [08:47:53:INFO] [0524/084753.915439:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [08:48:04:INFO] [0524/084804.466697:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [08:48:04:INFO] [0524/084804.525398:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [08:48:04:INFO] [0524/084804.525593:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [08:48:10:INFO] Test Case '-[AccountCollectionsTestCase testSignOutOnRemoveAccount]' passed (26.038 seconds). [08:48:10:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithPassphraseRequired]' started. [08:48:11:INFO] [0524/084811.811310:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:48:11:INFO] 2019-05-24 08:48:11.905 ios_chrome_unified_consent_egtests[27037:432665] -- 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. [08:48:13:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithPassphraseRequired]' passed (3.590 seconds). [08:48:13:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithSyncingMessage]' started. [08:48:15:INFO] 2019-05-24 08:48:15.511 ios_chrome_unified_consent_egtests[27037:432665] -- 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. [08:48:17:INFO] Test Case '-[AccountCollectionsTestCase testSyncItemWithSyncingMessage]' passed (3.595 seconds). [08:48:17:INFO] Test Suite 'AccountCollectionsTestCase' passed at 2019-05-24 08:48:17.368. [08:48:17:INFO] Executed 9 tests, with 0 failures (0 unexpected) in 143.524 (145.877) seconds [08:48:17:INFO] Test Suite 'BlockPopupsTestCase' started at 2019-05-24 08:48:17.369 [08:48:17:INFO] [0524/084817.370272:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [08:48:17:INFO] Test Case '-[BlockPopupsTestCase testAccessibilityOfBlockPopupSettings]' started. [08:48:37:INFO] Test Case '-[BlockPopupsTestCase testAccessibilityOfBlockPopupSettings]' passed (19.641 seconds). [08:48:37:INFO] Test Case '-[BlockPopupsTestCase testPopupsAllowed]' started. [08:48:38:INFO] [0524/084838.846674:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [08:48:42:INFO] Test Case '-[BlockPopupsTestCase testPopupsAllowed]' passed (5.828 seconds). [08:48:42:INFO] Test Case '-[BlockPopupsTestCase testPopupsBlocked]' started. [08:48:47:INFO] Test Case '-[BlockPopupsTestCase testPopupsBlocked]' passed (5.148 seconds). [08:48:48:INFO] Test Case '-[BlockPopupsTestCase testSettingsPageWithExceptions]' started. [08:49:10:INFO] Test Case '-[BlockPopupsTestCase testSettingsPageWithExceptions]' passed (22.492 seconds). [08:49:10:INFO] Test Suite 'BlockPopupsTestCase' passed at 2019-05-24 08:49:10.487. [08:49:10:INFO] Executed 4 tests, with 0 failures (0 unexpected) in 53.108 (53.118) seconds [08:49:10:INFO] Test Suite 'BookmarksAccessibilityTestCase' started at 2019-05-24 08:49:10.487 [08:49:10:INFO] [0524/084910.488579:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [08:49:10:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' started. [08:49:24:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksDeleteUndo]' passed (13.813 seconds). [08:49:24:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' started. [08:49:40:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksEditPage]' passed (16.492 seconds). [08:49:40:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' started. [08:49:57:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksFolderEditPage]' passed (16.287 seconds). [08:49:57:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' started. [08:50:06:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksLandingPage]' passed (9.685 seconds). [08:50:06:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' started. [08:50:23:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMovePage]' passed (16.372 seconds). [08:50:23:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' started. [08:50:42:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksMoveToNewFolderPage]' passed (18.905 seconds). [08:50:42:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' started. [08:50:54:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnBookmarksSelect]' passed (12.419 seconds). [08:50:54:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' started. [08:51:06:INFO] Test Case '-[BookmarksAccessibilityTestCase testAccessibilityOnMobileBookmarks]' passed (11.894 seconds). [08:51:06:INFO] Test Suite 'BookmarksAccessibilityTestCase' passed at 2019-05-24 08:51:06.368. [08:51:06:INFO] Executed 8 tests, with 0 failures (0 unexpected) in 115.866 (115.881) seconds [08:51:06:INFO] Test Suite 'BookmarksEntriesTestCase' started at 2019-05-24 08:51:06.369 [08:51:06:INFO] [0524/085106.370246:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [08:51:06:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' started. [08:51:20:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMixedSelection]' passed (14.012 seconds). [08:51:20:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' started. [08:51:34:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForMultipleURLSelection]' passed (14.086 seconds). [08:51:34:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' started. [08:51:48:INFO] Test Case '-[BookmarksEntriesTestCase testContextMenuForSingleURLSelection]' passed (14.098 seconds). [08:51:48:INFO] Test Case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' started. [08:51:59:INFO] 2019-05-24 08:51:59.594 ios_chrome_unified_consent_egtests[27037:432665] Returning local object of class NSString [08:52:02:INFO] [0524/085202.099279:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [08:52:03:INFO] Test Case '-[BookmarksEntriesTestCase testCopyFunctionalityOnSingleURL]' passed (14.769 seconds). [08:52:03:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' started. [08:52:18:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteMultipleNodes]' passed (15.401 seconds). [08:52:18:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' started. [08:52:33:INFO] Test Case '-[BookmarksEntriesTestCase testDeleteSingleURLNode]' passed (15.153 seconds). [08:52:33:INFO] Test Case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' started. [08:53:01:INFO] Test Case '-[BookmarksEntriesTestCase testEditTextOnSingleURL]' passed (27.481 seconds). [08:53:01:INFO] Test Case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' started. [08:53:15:INFO] Test Case '-[BookmarksEntriesTestCase testLongPressOnSingleURL]' passed (14.181 seconds). [08:53:15:INFO] Test Case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' started. [08:53:33:INFO] Test Case '-[BookmarksEntriesTestCase testMoveCancelledWhenAllSelectionDeleted]' passed (18.216 seconds). [08:53:33:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' started. [08:54:00:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMixedSelection]' passed (26.900 seconds). [08:54:00:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' started. [08:54:24:INFO] Test Case '-[BookmarksEntriesTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed (23.637 seconds). [08:54:24:INFO] Test Case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' started. [08:55:01:INFO] Test Case '-[BookmarksEntriesTestCase testMoveOnSingleURL]' passed (37.135 seconds). [08:55:01:INFO] Test Case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' started. [08:55:10:INFO] [0524/085510.803660:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [08:55:26:INFO] [0524/085526.857443:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /www.a.fr/ [08:55:48:INFO] [0524/085548.895931:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /www.a.fr/ [08:55:50:INFO] 2019-05-24 08:55:50.100 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:55:50:INFO] 2019-05-24 08:55:50.100 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:55:50:INFO] 2019-05-24 08:55:50.101 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:55:50:INFO] 2019-05-24 08:55:50.101 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [08:55:52:INFO] Test Case '-[BookmarksEntriesTestCase testOpenSingleBookmarkInNormalAndIncognitoTab]' passed (50.560 seconds). [08:55:52:INFO] Test Case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' started. [08:56:09:INFO] Test Case '-[BookmarksEntriesTestCase testSwipeToDeleteDisabledInEditMode]' passed (17.001 seconds). [08:56:09:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' started. [08:56:28:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromEditScreen]' passed (19.442 seconds). [08:56:28:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' started. [08:56:44:INFO] Test Case '-[BookmarksEntriesTestCase testUndoDeleteBookmarkFromSwipe]' passed (16.073 seconds). [08:56:44:INFO] Test Suite 'BookmarksEntriesTestCase' passed at 2019-05-24 08:56:44.532. [08:56:44:INFO] Executed 16 tests, with 0 failures (0 unexpected) in 338.146 (338.163) seconds [08:56:44:INFO] Test Suite 'BookmarksFoldersTestCase' started at 2019-05-24 08:56:44.533 [08:56:44:INFO] [0524/085644.534031:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [08:56:44:INFO] Test Case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' started. [08:56:46:INFO] [0524/085646.474293:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [08:57:04:INFO] Test Case '-[BookmarksFoldersTestCase testAddBookmarkInNewFolder]' passed (20.224 seconds). [08:57:04:INFO] Test Case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' started. [08:57:18:INFO] Test Case '-[BookmarksFoldersTestCase testCantDeleteFolderBeingEdited]' passed (13.830 seconds). [08:57:18:INFO] Test Case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' started. [08:57:35:INFO] Test Case '-[BookmarksFoldersTestCase testContextBarForSingleFolderSelection]' passed (16.564 seconds). [08:57:35:INFO] Test Case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' started. [08:57:48:INFO] Test Case '-[BookmarksFoldersTestCase testContextMenuForMultipleFolderSelection]' passed (13.793 seconds). [08:57:48:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' started. [08:58:14:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWhileMovingBookmarks]' passed (25.886 seconds). [08:58:14:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' started. [08:58:25:INFO] 2019-05-24 08:58:25.129 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [08:58:25:INFO] . [08:58:25:INFO] 2019-05-24 08:58:25.136 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Done. [08:58:28:INFO] 2019-05-24 08:58:28.669 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [08:58:28:INFO] . [08:58:28:INFO] 2019-05-24 08:58:28.675 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Done. [08:58:33:INFO] Test Case '-[BookmarksFoldersTestCase testCreateNewFolderWithContextBar]' passed (18.583 seconds). [08:58:33:INFO] Test Case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' started. [08:58:48:INFO] Test Case '-[BookmarksFoldersTestCase testDeleteSingleFolderNode]' passed (15.545 seconds). [08:58:48:INFO] Test Case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' started. [08:59:36:INFO] 2019-05-24 08:59:36.430 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [08:59:36:INFO] . [08:59:36:INFO] 2019-05-24 08:59:36.436 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Done. [08:59:42:INFO] Test Case '-[BookmarksFoldersTestCase testEditFunctionalityOnSingleFolder]' passed (53.653 seconds). [08:59:42:INFO] Test Case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' started. [09:00:00:INFO] Test Case '-[BookmarksFoldersTestCase testLongPressOnSingleFolder]' passed (17.426 seconds). [09:00:00:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' started. [09:00:26:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnMultipleFolder]' passed (26.018 seconds). [09:00:26:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' started. [09:00:58:INFO] Test Case '-[BookmarksFoldersTestCase testMoveFunctionalityOnSingleFolder]' passed (32.640 seconds). [09:00:58:INFO] Test Case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' started. [09:01:30:INFO] Test Case '-[BookmarksFoldersTestCase testNavigateAwayFromFolderBeingEdited]' passed (32.189 seconds). [09:01:30:INFO] Test Case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' started. [09:02:17:INFO] Test Case '-[BookmarksFoldersTestCase testNewFolderNameCommittedOnNavigatingAway]' passed (46.217 seconds). [09:02:17:INFO] Test Case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' started. [09:02:49:INFO] Test Case '-[BookmarksFoldersTestCase testStickyDefaultFolder]' passed (32.579 seconds). [09:02:49:INFO] Test Case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' started. [09:03:12:INFO] Test Case '-[BookmarksFoldersTestCase testWhenCurrentFolderDeletedInBackground]' passed (22.752 seconds). [09:03:12:INFO] Test Suite 'BookmarksFoldersTestCase' passed at 2019-05-24 09:03:12.445. [09:03:12:INFO] Executed 15 tests, with 0 failures (0 unexpected) in 387.896 (387.913) seconds [09:03:12:INFO] Test Suite 'BookmarksPromoTestCase' started at 2019-05-24 09:03:12.446 [09:03:12:INFO] [0524/090312.447546:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:03:12:INFO] Test Case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' started. [09:03:16:INFO] [0524/090316.917591:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [09:03:27:INFO] Test Case '-[BookmarksPromoTestCase testAutomaticSigninPromoDismiss]' passed (14.995 seconds). [09:03:27:INFO] Test Case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' started. [09:03:38:INFO] Test Case '-[BookmarksPromoTestCase testPromoNoThanksMakeItDisappear]' passed (10.899 seconds). [09:03:38:INFO] Test Case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' started. [09:03:53:INFO] Test Case '-[BookmarksPromoTestCase testPromoViewIsSeenOnlyInRootNode]' passed (14.719 seconds). [09:03:53:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' started. [09:04:05:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed (12.180 seconds). [09:04:05:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' started. [09:04:19:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed (13.997 seconds). [09:04:19:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' started. [09:04:35:INFO] Test Case '-[BookmarksPromoTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed (16.230 seconds). [09:04:35:INFO] Test Suite 'BookmarksPromoTestCase' passed at 2019-05-24 09:04:35.478. [09:04:35:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 83.020 (83.032) seconds [09:04:35:INFO] Test Suite 'BookmarksSearchTestCase' started at 2019-05-24 09:04:35.479 [09:04:35:INFO] [0524/090435.480676:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:04:35:INFO] Test Case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' started. [09:04:48:INFO] Test Case '-[BookmarksSearchTestCase testDisablesSearchOnEditMode]' passed (13.217 seconds). [09:04:48:INFO] Test Case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' started. [09:04:58:INFO] 2019-05-24 09:04:58.566 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key U. [09:04:58:INFO] 2019-05-24 09:04:58.567 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: U. [09:04:58:INFO] 2019-05-24 09:04:58.688 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key R. [09:04:58:INFO] 2019-05-24 09:04:58.790 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:04:58:INFO] 2019-05-24 09:04:58.844 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: R. [09:04:58:INFO] 2019-05-24 09:04:58.926 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key L. [09:04:59:INFO] 2019-05-24 09:04:59.029 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:04:59:INFO] 2019-05-24 09:04:59.083 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: L. [09:04:59:INFO] 2019-05-24 09:04:59.160 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:04:59:INFO] . [09:04:59:INFO] 2019-05-24 09:04:59.163 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Search. [09:05:15:INFO] Test Case '-[BookmarksSearchTestCase testMoveFunctionalityOnMultipleUrlSelection]' passed (26.427 seconds). [09:05:15:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' started. [09:05:27:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnMobileBookmarks]' passed (12.294 seconds). [09:05:27:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' started. [09:05:37:INFO] Test Case '-[BookmarksSearchTestCase testSearchBarShownOnRoot]' passed (10.067 seconds). [09:05:37:INFO] Test Case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' started. [09:05:47:INFO] 2019-05-24 09:05:47.351 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key X. [09:05:47:INFO] 2019-05-24 09:05:47.355 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: X. [09:05:51:INFO] Test Case '-[BookmarksSearchTestCase testSearchCancelRestoresNodeBookmarks]' passed (14.419 seconds). [09:05:51:INFO] Test Case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' started. [09:06:01:INFO] 2019-05-24 09:06:01.742 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:06:01:INFO] 2019-05-24 09:06:01.744 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:06:02:INFO] 2019-05-24 09:06:02.056 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:06:02:INFO] 2019-05-24 09:06:02.063 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:06:02:INFO] 2019-05-24 09:06:02.132 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:06:02:INFO] 2019-05-24 09:06:02.133 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:06:02:INFO] 2019-05-24 09:06:02.190 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key s. [09:06:02:INFO] 2019-05-24 09:06:02.191 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: s. [09:06:02:INFO] 2019-05-24 09:06:02.250 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key t. [09:06:02:INFO] 2019-05-24 09:06:02.250 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: t. [09:06:02:INFO] 2019-05-24 09:06:02.307 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:06:02:INFO] . [09:06:02:INFO] 2019-05-24 09:06:02.310 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Search. [09:06:05:INFO] Test Case '-[BookmarksSearchTestCase testSearchDisablesNewFolderButtonOnNavigationBar]' passed (14.054 seconds). [09:06:05:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' started. [09:06:15:INFO] 2019-05-24 09:06:15.828 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key U. [09:06:15:INFO] 2019-05-24 09:06:15.830 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: U. [09:06:16:INFO] 2019-05-24 09:06:16.070 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key R. [09:06:16:INFO] 2019-05-24 09:06:16.172 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:06:16:INFO] 2019-05-24 09:06:16.224 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: R. [09:06:16:INFO] 2019-05-24 09:06:16.299 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key L. [09:06:16:INFO] 2019-05-24 09:06:16.402 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:06:16:INFO] 2019-05-24 09:06:16.457 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: L. [09:06:16:INFO] 2019-05-24 09:06:16.533 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:06:16:INFO] . [09:06:16:INFO] 2019-05-24 09:06:16.536 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Search. [09:06:22:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeDeleteOnMultipleURL]' passed (16.419 seconds). [09:06:22:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' started. [09:06:32:INFO] 2019-05-24 09:06:32.231 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:06:32:INFO] 2019-05-24 09:06:32.233 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:06:32:INFO] 2019-05-24 09:06:32.502 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:06:32:INFO] 2019-05-24 09:06:32.511 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:06:32:INFO] 2019-05-24 09:06:32.578 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:06:32:INFO] 2019-05-24 09:06:32.578 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:06:32:INFO] 2019-05-24 09:06:32.646 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key s. [09:06:32:INFO] 2019-05-24 09:06:32.647 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: s. [09:06:32:INFO] 2019-05-24 09:06:32.702 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key t. [09:06:32:INFO] 2019-05-24 09:06:32.703 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: t. [09:06:32:INFO] 2019-05-24 09:06:32.759 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:06:32:INFO] . [09:06:32:INFO] 2019-05-24 09:06:32.761 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Search. [09:06:43:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditModeEditOnSingleURL]' passed (20.996 seconds). [09:06:43:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' started. [09:06:51:INFO] 2019-05-24 09:06:51.032 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:06:51:INFO] 2019-05-24 09:06:51.034 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:06:51:INFO] 2019-05-24 09:06:51.244 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:06:51:INFO] 2019-05-24 09:06:51.246 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:06:51:INFO] 2019-05-24 09:06:51.324 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:06:51:INFO] 2019-05-24 09:06:51.329 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:06:51:INFO] 2019-05-24 09:06:51.391 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key s. [09:06:51:INFO] 2019-05-24 09:06:51.392 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: s. [09:06:51:INFO] 2019-05-24 09:06:51.456 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key t. [09:06:51:INFO] 2019-05-24 09:06:51.457 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: t. [09:06:51:INFO] 2019-05-24 09:06:51.513 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:06:51:INFO] . [09:06:51:INFO] 2019-05-24 09:06:51.515 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Search. [09:07:02:INFO] Test Case '-[BookmarksSearchTestCase testSearchEditPossibleOnRoot]' passed (19.394 seconds). [09:07:02:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' started. [09:07:12:INFO] 2019-05-24 09:07:12.646 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:07:12:INFO] 2019-05-24 09:07:12.649 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:07:12:INFO] 2019-05-24 09:07:12.865 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key o. [09:07:12:INFO] 2019-05-24 09:07:12.866 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: o. [09:07:12:INFO] 2019-05-24 09:07:12.947 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key l. [09:07:12:INFO] 2019-05-24 09:07:12.949 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: l. [09:07:13:INFO] 2019-05-24 09:07:13.046 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key d. [09:07:13:INFO] 2019-05-24 09:07:13.047 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: d. [09:07:13:INFO] 2019-05-24 09:07:13.111 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key e. [09:07:13:INFO] 2019-05-24 09:07:13.112 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: e. [09:07:13:INFO] 2019-05-24 09:07:13.179 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:07:13:INFO] 2019-05-24 09:07:13.179 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:07:13:INFO] 2019-05-24 09:07:13.244 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key . [09:07:13:INFO] 2019-05-24 09:07:13.246 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: space. [09:07:13:INFO] 2019-05-24 09:07:13.312 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key 1. [09:07:13:INFO] 2019-05-24 09:07:13.415 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: more, numbers. [09:07:13:INFO] 2019-05-24 09:07:13.483 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: 1. [09:07:18:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolderCanBeSwipedToDelete]' passed (15.664 seconds). [09:07:18:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolders]' started. [09:07:34:INFO] 2019-05-24 09:07:34.978 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:07:34:INFO] 2019-05-24 09:07:34.983 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:07:35:INFO] 2019-05-24 09:07:35.169 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key o. [09:07:35:INFO] 2019-05-24 09:07:35.170 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: o. [09:07:35:INFO] 2019-05-24 09:07:35.252 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key l. [09:07:35:INFO] 2019-05-24 09:07:35.253 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: l. [09:07:35:INFO] 2019-05-24 09:07:35.326 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key d. [09:07:35:INFO] 2019-05-24 09:07:35.327 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: d. [09:07:35:INFO] 2019-05-24 09:07:35.403 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key e. [09:07:35:INFO] 2019-05-24 09:07:35.404 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: e. [09:07:35:INFO] 2019-05-24 09:07:35.472 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:07:35:INFO] 2019-05-24 09:07:35.473 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:07:35:INFO] 2019-05-24 09:07:35.539 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key . [09:07:35:INFO] 2019-05-24 09:07:35.541 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: space. [09:07:35:INFO] 2019-05-24 09:07:35.611 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key 1. [09:07:35:INFO] 2019-05-24 09:07:35.714 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: more, numbers. [09:07:35:INFO] 2019-05-24 09:07:35.765 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: 1. [09:07:35:INFO] 2019-05-24 09:07:35.827 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key .. [09:07:35:INFO] 2019-05-24 09:07:35.829 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: .. [09:07:35:INFO] 2019-05-24 09:07:35.895 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key 1. [09:07:35:INFO] 2019-05-24 09:07:35.896 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: 1. [09:07:41:INFO] 2019-05-24 09:07:41.884 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:07:41:INFO] 2019-05-24 09:07:41.886 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:07:42:INFO] 2019-05-24 09:07:42.122 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key o. [09:07:42:INFO] 2019-05-24 09:07:42.123 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: o. [09:07:42:INFO] 2019-05-24 09:07:42.198 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key l. [09:07:42:INFO] 2019-05-24 09:07:42.199 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: l. [09:07:42:INFO] 2019-05-24 09:07:42.272 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key d. [09:07:42:INFO] 2019-05-24 09:07:42.272 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: d. [09:07:42:INFO] 2019-05-24 09:07:42.350 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key e. [09:07:42:INFO] 2019-05-24 09:07:42.350 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: e. [09:07:42:INFO] 2019-05-24 09:07:42.414 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:07:42:INFO] 2019-05-24 09:07:42.415 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:07:42:INFO] 2019-05-24 09:07:42.480 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key . [09:07:42:INFO] 2019-05-24 09:07:42.481 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: space. [09:07:42:INFO] 2019-05-24 09:07:42.547 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key 2. [09:07:42:INFO] 2019-05-24 09:07:42.650 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: more, numbers. [09:07:42:INFO] 2019-05-24 09:07:42.701 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: 2. [09:07:46:INFO] 2019-05-24 09:07:46.063 ios_chrome_unified_consent_egtests[27037:432665] changing items while animating can result in a corrupted navigation bar [09:07:49:INFO] Test Case '-[BookmarksSearchTestCase testSearchFolders]' passed (30.573 seconds). [09:07:49:INFO] Test Case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' started. [09:07:59:INFO] 2019-05-24 09:07:59.342 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:07:59:INFO] 2019-05-24 09:07:59.343 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:07:59:INFO] 2019-05-24 09:07:59.451 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:07:59:INFO] 2019-05-24 09:07:59.452 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:07:59:INFO] 2019-05-24 09:07:59.524 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:07:59:INFO] 2019-05-24 09:07:59.524 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:07:59:INFO] 2019-05-24 09:07:59.592 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key s. [09:07:59:INFO] 2019-05-24 09:07:59.593 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: s. [09:07:59:INFO] 2019-05-24 09:07:59.658 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key t. [09:07:59:INFO] 2019-05-24 09:07:59.659 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: t. [09:08:03:INFO] Test Case '-[BookmarksSearchTestCase testSearchHidesNavigationBar]' passed (14.095 seconds). [09:08:03:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' started. [09:08:12:INFO] 2019-05-24 09:08:12.963 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:08:12:INFO] 2019-05-24 09:08:12.966 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:08:13:INFO] 2019-05-24 09:08:13.196 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key o. [09:08:13:INFO] 2019-05-24 09:08:13.197 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: o. [09:08:13:INFO] 2019-05-24 09:08:13.272 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key l. [09:08:13:INFO] 2019-05-24 09:08:13.274 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: l. [09:08:13:INFO] 2019-05-24 09:08:13.356 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key d. [09:08:13:INFO] 2019-05-24 09:08:13.357 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: d. [09:08:13:INFO] 2019-05-24 09:08:13.427 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key e. [09:08:13:INFO] 2019-05-24 09:08:13.428 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: e. [09:08:13:INFO] 2019-05-24 09:08:13.495 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:08:13:INFO] 2019-05-24 09:08:13.495 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:08:13:INFO] 2019-05-24 09:08:13.561 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key . [09:08:13:INFO] 2019-05-24 09:08:13.562 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: space. [09:08:13:INFO] 2019-05-24 09:08:13.628 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key 1. [09:08:13:INFO] 2019-05-24 09:08:13.731 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: more, numbers. [09:08:13:INFO] 2019-05-24 09:08:13.782 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: 1. [09:08:13:INFO] 2019-05-24 09:08:13.843 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key .. [09:08:13:INFO] 2019-05-24 09:08:13.846 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: .. [09:08:13:INFO] 2019-05-24 09:08:13.912 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key 1. [09:08:13:INFO] 2019-05-24 09:08:13.912 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: 1. [09:08:24:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnFolder]' passed (21.406 seconds). [09:08:24:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' started. [09:08:34:INFO] 2019-05-24 09:08:34.387 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:08:34:INFO] 2019-05-24 09:08:34.389 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:08:34:INFO] 2019-05-24 09:08:34.520 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:08:34:INFO] 2019-05-24 09:08:34.521 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:08:34:INFO] 2019-05-24 09:08:34.625 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:08:34:INFO] 2019-05-24 09:08:34.626 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:08:34:INFO] 2019-05-24 09:08:34.690 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key s. [09:08:34:INFO] 2019-05-24 09:08:34.691 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: s. [09:08:34:INFO] 2019-05-24 09:08:34.750 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key t. [09:08:34:INFO] 2019-05-24 09:08:34.751 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: t. [09:08:45:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressEditOnURL]' passed (20.926 seconds). [09:08:45:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' started. [09:09:00:INFO] Test Case '-[BookmarksSearchTestCase testSearchLongPressOnScrimCancelsSearchController]' passed (15.244 seconds). [09:09:00:INFO] Test Case '-[BookmarksSearchTestCase testSearchResults]' started. [09:09:10:INFO] 2019-05-24 09:09:10.796 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key o. [09:09:11:INFO] 2019-05-24 09:09:11.000 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:09:11:INFO] 2019-05-24 09:09:11.060 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: o. [09:09:11:INFO] 2019-05-24 09:09:11.596 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key n. [09:09:11:INFO] 2019-05-24 09:09:11.597 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: n. [09:09:12:INFO] 2019-05-24 09:09:12.594 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key y. [09:09:12:INFO] 2019-05-24 09:09:12.594 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: y. [09:09:16:INFO] Test Case '-[BookmarksSearchTestCase testSearchResults]' passed (15.924 seconds). [09:09:16:INFO] Test Case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' started. [09:09:27:INFO] 2019-05-24 09:09:27.005 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:09:27:INFO] 2019-05-24 09:09:27.211 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:09:27:INFO] 2019-05-24 09:09:27.271 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:09:27:INFO] 2019-05-24 09:09:27.703 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key . [09:09:27:INFO] 2019-05-24 09:09:27.704 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: delete. [09:09:32:INFO] Test Case '-[BookmarksSearchTestCase testSearchScrimShownWhenSearchBoxEnabled]' passed (15.462 seconds). [09:09:32:INFO] Test Case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' started. [09:09:46:INFO] Test Case '-[BookmarksSearchTestCase testSearchTapOnScrimCancelsSearchController]' passed (14.642 seconds). [09:09:46:INFO] Test Case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' started. [09:09:56:INFO] 2019-05-24 09:09:56.593 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key F. [09:09:56:INFO] 2019-05-24 09:09:56.595 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: F. [09:09:56:INFO] 2019-05-24 09:09:56.802 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key i. [09:09:56:INFO] 2019-05-24 09:09:56.803 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: i. [09:09:56:INFO] 2019-05-24 09:09:56.870 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key r. [09:09:56:INFO] 2019-05-24 09:09:56.871 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: r. [09:09:56:INFO] 2019-05-24 09:09:56.933 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key s. [09:09:56:INFO] 2019-05-24 09:09:56.934 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: s. [09:09:56:INFO] 2019-05-24 09:09:56.993 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key t. [09:09:56:INFO] 2019-05-24 09:09:56.994 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: t. [09:09:57:INFO] 2019-05-24 09:09:57.060 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key . [09:09:57:INFO] 2019-05-24 09:09:57.062 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: space. [09:09:57:INFO] 2019-05-24 09:09:57.118 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key U. [09:09:57:INFO] 2019-05-24 09:09:57.220 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:09:57:INFO] 2019-05-24 09:09:57.274 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: U. [09:09:57:INFO] 2019-05-24 09:09:57.346 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key R. [09:09:57:INFO] 2019-05-24 09:09:57.447 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:09:57:INFO] 2019-05-24 09:09:57.500 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: R. [09:09:57:INFO] 2019-05-24 09:09:57.573 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key L. [09:09:57:INFO] 2019-05-24 09:09:57.676 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:09:57:INFO] 2019-05-24 09:09:57.730 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: L. [09:10:02:INFO] Test Case '-[BookmarksSearchTestCase testSearchUrlCanBeSwipedToDelete]' passed (15.981 seconds). [09:10:02:INFO] Test Case '-[BookmarksSearchTestCase testSearchWithNoResults]' started. [09:10:12:INFO] 2019-05-24 09:10:12.576 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key z. [09:10:12:INFO] 2019-05-24 09:10:12.781 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: shift. [09:10:12:INFO] 2019-05-24 09:10:12.844 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: z. [09:10:12:INFO] 2019-05-24 09:10:12.909 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key z. [09:10:12:INFO] 2019-05-24 09:10:12.909 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: z. [09:10:12:INFO] 2019-05-24 09:10:12.969 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:10:12:INFO] . [09:10:12:INFO] 2019-05-24 09:10:12.971 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Search. [09:10:16:INFO] Test Case '-[BookmarksSearchTestCase testSearchWithNoResults]' passed (13.975 seconds). [09:10:16:INFO] Test Suite 'BookmarksSearchTestCase' passed at 2019-05-24 09:10:16.681. [09:10:16:INFO] Executed 20 tests, with 0 failures (0 unexpected) in 341.180 (341.202) seconds [09:10:16:INFO] Test Suite 'BookmarksTestCase' started at 2019-05-24 09:10:16.682 [09:10:16:INFO] [0524/091016.683704:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:10:16:INFO] Test Case '-[BookmarksTestCase testAddRemoveBookmark]' started. [09:10:18:INFO] [0524/091018.663403:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [09:10:54:INFO] Test Case '-[BookmarksTestCase testAddRemoveBookmark]' passed (37.514 seconds). [09:10:54:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' started. [09:11:09:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInMultipleSelectionModes]' passed (15.528 seconds). [09:11:09:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' started. [09:11:25:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarInSingleSelectionModes]' passed (15.401 seconds). [09:11:25:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarShown]' started. [09:11:37:INFO] Test Case '-[BookmarksTestCase testBookmarkContextBarShown]' passed (12.251 seconds). [09:11:37:INFO] Test Case '-[BookmarksTestCase testBookmarkMultipleTabs]' started. [09:11:53:INFO] Test Case '-[BookmarksTestCase testBookmarkMultipleTabs]' passed (16.513 seconds). [09:11:53:INFO] Test Case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' started. [09:12:10:INFO] 2019-05-24 09:12:10.608 ios_chrome_unified_consent_egtests[27037:432665] Attempting to type key [09:12:10:INFO] . [09:12:10:INFO] 2019-05-24 09:12:10.615 ios_chrome_unified_consent_egtests[27037:432665] Tapping on key: Done. [09:12:16:INFO] Test Case '-[BookmarksTestCase testBookmarksExceedsScreenHeight]' passed (22.453 seconds). [09:12:16:INFO] Test Case '-[BookmarksTestCase testBookmarksURLDisabled]' started. [09:12:21:INFO] Test Case '-[BookmarksTestCase testBookmarksURLDisabled]' passed (4.900 seconds). [09:12:21:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreated]' started. [09:12:52:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreated]' passed (31.621 seconds). [09:12:52:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' started. [09:13:19:INFO] Test Case '-[BookmarksTestCase testCachePositionIsRecreatedWhenNodeIsMoved]' passed (26.913 seconds). [09:13:19:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' started. [09:13:42:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsDeleted]' passed (22.518 seconds). [09:13:42:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' started. [09:14:00:INFO] Test Case '-[BookmarksTestCase testCachePositionIsResetWhenNodeIsPermanentAndEmpty]' passed (18.138 seconds). [09:14:00:INFO] Test Case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' started. [09:14:23:INFO] Test Case '-[BookmarksTestCase testEmptyBackgroundAndSelectButton]' passed (23.014 seconds). [09:14:23:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' started. [09:14:43:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsNotRegistered_EditBookmark]' passed (20.104 seconds). [09:14:43:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' started. [09:14:51:INFO] Test Case '-[BookmarksTestCase testKeyboardCommandsRegistered_AddBookmark]' passed (7.632 seconds). [09:14:51:INFO] Test Case '-[BookmarksTestCase testMoveDoesSaveOnSave]' started. [09:15:17:INFO] Test Case '-[BookmarksTestCase testMoveDoesSaveOnSave]' passed (26.586 seconds). [09:15:17:INFO] Test Case '-[BookmarksTestCase testNavigateBackWithGesture]' started. [09:15:36:INFO] Test Case '-[BookmarksTestCase testNavigateBackWithGesture]' passed (18.308 seconds). [09:15:36:INFO] Test Suite 'BookmarksTestCase' passed at 2019-05-24 09:15:36.096. [09:15:36:INFO] Executed 16 tests, with 0 failures (0 unexpected) in 319.395 (319.414) seconds [09:15:36:INFO] Test Suite 'FirstRunTestCase' started at 2019-05-24 09:15:36.097 [09:15:36:INFO] [0524/091536.098130:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:15:36:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' started. [09:15:43:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' passed (7.171 seconds). [09:15:43:INFO] Test Case '-[FirstRunTestCase testPrivacy]' started. [09:15:50:INFO] Test Case '-[FirstRunTestCase testPrivacy]' passed (7.176 seconds). [09:15:50:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' started. [09:15:54:INFO] [0524/091554.533509:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:15:54:INFO] [0524/091554.535332:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:15:54:INFO] [0524/091554.559554:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:15:54:INFO] [0524/091554.559690:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:15:54:INFO] [0524/091554.608662:ERROR:account_tracker.cc(264)] OnOAuthError [09:15:54:INFO] [0524/091554.608804:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:15:57:INFO] [0524/091557.773881:INFO:profile_sync_service.cc(1746)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [09:15:59:INFO] [0524/091558.998529:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:15:59:INFO] [0524/091559.032974:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:15:59:INFO] [0524/091559.033180:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:15:59:INFO] [0524/091559.361744:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [09:16:01:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' passed (10.614 seconds). [09:16:01:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' started. [09:16:02:INFO] [0524/091602.878255:WARNING:first_run_egtest.mm(203)] Skipping test as there is no undo operation when Unified Consent is enabled. [09:16:04:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' passed (3.692 seconds). [09:16:04:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' started. [09:16:12:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' passed (7.335 seconds). [09:16:12:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' started. [09:16:18:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' passed (6.481 seconds). [09:16:18:INFO] Test Suite 'FirstRunTestCase' passed at 2019-05-24 09:16:18.578. [09:16:18:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 42.468 (42.481) seconds [09:16:18:INFO] Test Suite 'GoogleServicesSettingsTestCase' started at 2019-05-24 09:16:18.579 [09:16:18:INFO] [0524/091618.580134:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:16:18:INFO] Test Case '-[GoogleServicesSettingsTestCase testInterruptSigninFromGoogleServicesSettings]' started. [09:16:28:INFO] [0524/091628.634364:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:16:28:INFO] [0524/091628.636617:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:16:28:INFO] [0524/091628.636723:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:16:28:INFO] [0524/091628.637372:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:16:28:INFO] [0524/091628.700735:ERROR:account_tracker.cc(264)] OnOAuthError [09:16:28:INFO] [0524/091628.700842:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:16:44:INFO] [0524/091644.083652:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:16:44:INFO] [0524/091644.156952:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:16:44:INFO] [0524/091644.157150:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:16:47:INFO] Test Case '-[GoogleServicesSettingsTestCase testInterruptSigninFromGoogleServicesSettings]' passed (28.612 seconds). [09:16:47:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpenGoogleServicesSettings]' started. [09:16:59:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpenGoogleServicesSettings]' passed (12.190 seconds). [09:16:59:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpeningServices]' started. [09:17:11:INFO] Test Case '-[GoogleServicesSettingsTestCase testOpeningServices]' passed (11.797 seconds). [09:17:11:INFO] Test Suite 'GoogleServicesSettingsTestCase' passed at 2019-05-24 09:17:11.192. [09:17:11:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 52.598 (52.613) seconds [09:17:11:INFO] Test Suite 'SettingsTestCase' started at 2019-05-24 09:17:11.192 [09:17:11:INFO] [0524/091711.193646:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:17:11:INFO] Test Case '-[SettingsTestCase testAccessibilityOnAddresses]' started. [09:17:25:INFO] Test Case '-[SettingsTestCase testAccessibilityOnAddresses]' passed (13.960 seconds). [09:17:25:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthManagementSettingsPage]' started. [09:17:40:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthManagementSettingsPage]' passed (15.583 seconds). [09:17:40:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthPreloadWebpagesSettingsPage]' started. [09:17:58:INFO] Test Case '-[SettingsTestCase testAccessibilityOnBandwidthPreloadWebpagesSettingsPage]' passed (17.745 seconds). [09:17:58:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsBlockPopupsPage]' started. [09:18:16:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsBlockPopupsPage]' passed (17.713 seconds). [09:18:16:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsPage]' started. [09:18:31:INFO] Test Case '-[SettingsTestCase testAccessibilityOnContentSettingsPage]' passed (15.570 seconds). [09:18:31:INFO] Test Case '-[SettingsTestCase testAccessibilityOnGoogleChrome]' started. [09:18:49:INFO] Test Case '-[SettingsTestCase testAccessibilityOnGoogleChrome]' passed (17.231 seconds). [09:18:49:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPaymentMethods]' started. [09:19:02:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPaymentMethods]' passed (13.949 seconds). [09:19:02:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyClearBrowsingHistoryPage]' started. [09:19:19:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyClearBrowsingHistoryPage]' passed (16.191 seconds). [09:19:19:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyHandoffSettingsPage]' started. [09:19:35:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacyHandoffSettingsPage]' passed (16.082 seconds). [09:19:35:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacySettingsPage]' started. [09:19:49:INFO] Test Case '-[SettingsTestCase testAccessibilityOnPrivacySettingsPage]' passed (13.944 seconds). [09:19:49:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSearchEngine]' started. [09:20:03:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSearchEngine]' passed (13.967 seconds). [09:20:03:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSendUsageData]' started. [09:20:04:INFO] 2019-05-24 09:20:04.956 ios_chrome_unified_consent_egtests[27037:432665] -- 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]. [09:20:06:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSendUsageData]' passed (3.768 seconds). [09:20:06:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSettingsPage]' started. [09:20:16:INFO] Test Case '-[SettingsTestCase testAccessibilityOnSettingsPage]' passed (9.605 seconds). [09:20:16:INFO] Test Case '-[SettingsTestCase testAccessibilityOnVoiceSearch]' started. [09:20:30:INFO] Test Case '-[SettingsTestCase testAccessibilityOnVoiceSearch]' passed (13.940 seconds). [09:20:30:INFO] Test Case '-[SettingsTestCase testBreakpadReporting]' started. [09:20:34:INFO] Test Case '-[SettingsTestCase testBreakpadReporting]' passed (3.767 seconds). [09:20:34:INFO] Test Case '-[SettingsTestCase testBreakpadReportingFirstLaunch]' started. [09:20:37:INFO] Test Case '-[SettingsTestCase testBreakpadReportingFirstLaunch]' passed (3.767 seconds). [09:20:38:INFO] Test Case '-[SettingsTestCase testClearCookies]' started. [09:20:39:INFO] [0524/092039.909216:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [09:20:59:INFO] Test Case '-[SettingsTestCase testClearCookies]' passed (21.769 seconds). [09:20:59:INFO] Test Case '-[SettingsTestCase testMetricsReporting]' started. [09:21:03:INFO] Test Case '-[SettingsTestCase testMetricsReporting]' passed (3.758 seconds). [09:21:03:INFO] Test Case '-[SettingsTestCase testSettingsKeyboardCommands]' started. [09:21:15:INFO] Test Case '-[SettingsTestCase testSettingsKeyboardCommands]' passed (12.327 seconds). [09:21:15:INFO] Test Case '-[SettingsTestCase testSettingsSignedOutIncognito]' started. [09:21:25:INFO] 2019-05-24 09:21:25.292 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [09:21:25:INFO] 2019-05-24 09:21:25.292 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [09:21:25:INFO] 2019-05-24 09:21:25.292 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [09:21:25:INFO] 2019-05-24 09:21:25.292 ios_chrome_unified_consent_egtests[27037:432665] could not create directory "" for future sandbox extension, error Error Domain=NSCocoaErrorDomain Code=512 "The file “” couldn’t be saved." UserInfo={NSFilePath=} [09:21:27:INFO] Test Case '-[SettingsTestCase testSettingsSignedOutIncognito]' passed (11.425 seconds). [09:21:27:INFO] Test Suite 'SettingsTestCase' passed at 2019-05-24 09:21:27.281. [09:21:27:INFO] Executed 20 tests, with 0 failures (0 unexpected) in 256.061 (256.088) seconds [09:21:27:INFO] Test Suite 'SigninInteractionControllerTestCase' started at 2019-05-24 09:21:27.281 [09:21:27:INFO] [0524/092127.283072:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:21:27:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' started. [09:21:36:INFO] [0524/092136.474734:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:21:36:INFO] [0524/092136.481689:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:21:36:INFO] [0524/092136.555095:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:21:36:INFO] [0524/092136.555223:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:21:36:INFO] [0524/092136.619185:ERROR:account_tracker.cc(264)] OnOAuthError [09:21:36:INFO] [0524/092136.619383:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:21:36:INFO] [0524/092136.661971:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:21:36:INFO] [0524/092136.662152:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID [09:21:36:INFO] [0524/092136.712098:ERROR:account_tracker.cc(264)] OnOAuthError [09:21:36:INFO] [0524/092136.712213:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [09:21:47:INFO] [0524/092147.903333:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:21:47:INFO] [0524/092147.938818:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:21:47:INFO] [0524/092147.938993:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:22:07:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelAuthenticationFlow]' passed (39.996 seconds). [09:22:07:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' started. [09:22:30:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelFromBookmarks]' passed (23.443 seconds). [09:22:30:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' started. [09:22:37:INFO] 2019-05-24 09:22:37.640 ios_chrome_unified_consent_egtests[27037:432665] Warning: Attempt to present <IdentityChooserViewController: 0x7ff37dc24c70> on <UnifiedConsentViewController: 0x7ff37dd11820> whose view is not in the window hierarchy! [09:22:51:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInCancelIdentityPicker]' passed (21.149 seconds). [09:22:51:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' started. [09:23:01:INFO] [0524/092301.726618:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:23:01:INFO] [0524/092301.726800:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:23:01:INFO] [0524/092301.780907:ERROR:account_tracker.cc(264)] OnOAuthError [09:23:01:INFO] [0524/092301.781010:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:23:01:INFO] [0524/092301.823683:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [09:23:13:INFO] [0524/092313.068336:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:23:13:INFO] [0524/092313.103379:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:23:13:INFO] [0524/092313.103498:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:23:18:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInDisconnectFromChrome]' passed (26.897 seconds). [09:23:18:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' started. [09:23:28:INFO] [0524/092328.572551:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:23:28:INFO] [0524/092328.572718:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:23:28:INFO] [0524/092328.636252:ERROR:account_tracker.cc(264)] OnOAuthError [09:23:28:INFO] [0524/092328.636402:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:23:28:INFO] [0524/092328.724524:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [09:23:32:INFO] [0524/092332.365193:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:23:32:INFO] [0524/092332.385596:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:23:32:INFO] [0524/092332.385771:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:23:34:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOneUser]' passed (15.647 seconds). [09:23:34:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' started. [09:23:44:INFO] [0524/092344.248285:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:23:44:INFO] [0524/092344.248420:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:23:44:INFO] [0524/092344.293727:ERROR:account_tracker.cc(264)] OnOAuthError [09:23:44:INFO] [0524/092344.293829:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:23:47:INFO] [0524/092347.472339:INFO:profile_sync_service.cc(1746)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [09:23:47:INFO] [0524/092347.678711:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [09:23:49:INFO] [0524/092349.051612:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:23:49:INFO] [0524/092349.154649:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:23:49:INFO] [0524/092349.154807:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:23:52:INFO] Test Case '-[SigninInteractionControllerTestCase testSignInOpenSettings]' passed (17.706 seconds). [09:23:52:INFO] Test Suite 'SigninInteractionControllerTestCase' passed at 2019-05-24 09:23:52.142. [09:23:52:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 144.838 (144.860) seconds [09:23:52:INFO] Test Suite 'SigninInteractionControllerUnityEnabledTestCase' started at 2019-05-24 09:23:52.142 [09:23:52:INFO] [0524/092352.143832:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:23:52:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testAddAccountAutomatically]' started. [09:24:03:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testAddAccountAutomatically]' passed (11.220 seconds). [09:24:03:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testRemoveLastAccount]' started. [09:24:15:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testRemoveLastAccount]' passed (12.111 seconds). [09:24:15:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testSignInSwitchAccountsAndImportData]' started. [09:24:24:INFO] [0524/092424.933694:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:24:24:INFO] [0524/092424.935857:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:24:24:INFO] [0524/092424.941130:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:24:24:INFO] [0524/092424.941261:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:24:25:INFO] [0524/092425.001382:ERROR:account_tracker.cc(264)] OnOAuthError [09:24:25:INFO] [0524/092425.001539:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:24:25:INFO] [0524/092425.021631:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:24:25:INFO] [0524/092425.021912:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID [09:24:25:INFO] [0524/092425.072565:ERROR:account_tracker.cc(264)] OnOAuthError [09:24:25:INFO] [0524/092425.072721:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [09:24:36:INFO] [0524/092436.359711:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:24:36:INFO] [0524/092436.397009:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:24:36:INFO] [0524/092436.397187:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:24:45:INFO] [0524/092445.366737:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:24:45:INFO] [0524/092445.366904:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:24:45:INFO] [0524/092445.371136:ERROR:account_tracker.cc(264)] OnOAuthError [09:24:45:INFO] [0524/092445.371253:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:24:45:INFO] [0524/092445.417883:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:24:45:INFO] [0524/092445.418159:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo2ID [09:24:45:INFO] [0524/092445.482090:ERROR:account_tracker.cc(264)] OnOAuthError [09:24:45:INFO] [0524/092445.482582:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo2ID [09:24:45:INFO] [0524/092445.528067:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [09:24:47:INFO] [0524/092447.890879:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:24:47:INFO] [0524/092447.921937:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:24:47:INFO] [0524/092447.922125:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:24:51:INFO] Test Case '-[SigninInteractionControllerUnityEnabledTestCase testSignInSwitchAccountsAndImportData]' passed (35.810 seconds). [09:24:51:INFO] Test Suite 'SigninInteractionControllerUnityEnabledTestCase' passed at 2019-05-24 09:24:51.295. [09:24:51:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 59.141 (59.153) seconds [09:24:51:INFO] Test Suite 'SigninSettingsTestCase' started at 2019-05-24 09:24:51.296 [09:24:51:INFO] [0524/092451.297635:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:24:51:INFO] Test Case '-[SigninSettingsTestCase testAutomaticSigninPromoDismiss]' started. [09:25:06:INFO] Test Case '-[SigninSettingsTestCase testAutomaticSigninPromoDismiss]' passed (15.194 seconds). [09:25:06:INFO] Test Case '-[SigninSettingsTestCase testDissmissSigninPromo]' started. [09:25:16:INFO] Test Case '-[SigninSettingsTestCase testDissmissSigninPromo]' passed (9.963 seconds). [09:25:16:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithColdStateUsingPrimaryButton]' started. [09:25:28:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithColdStateUsingPrimaryButton]' passed (12.209 seconds). [09:25:28:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' started. [09:25:36:INFO] [0524/092536.197812:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:25:36:INFO] [0524/092536.200659:WARNING:sync_encryption_handler_impl.cc(1093)] Nigori had empty encryption keybag. [09:25:36:INFO] [0524/092536.282965:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:25:36:INFO] [0524/092536.283117:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:25:36:INFO] [0524/092536.336640:ERROR:account_tracker.cc(264)] OnOAuthError [09:25:36:INFO] [0524/092536.336797:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:25:38:INFO] [0524/092538.419667:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:25:38:INFO] [0524/092538.519675:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:25:38:INFO] [0524/092538.519820:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:25:41:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingPrimaryButton]' passed (12.829 seconds). [09:25:41:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' started. [09:25:51:INFO] [0524/092551.605315:ERROR:account_info_fetcher.cc(75)] OnOAuthError [09:25:51:INFO] [0524/092551.605441:WARNING:account_fetcher_service.cc(333)] Failed to get UserInfo for foo1ID [09:25:51:INFO] [0524/092551.666787:ERROR:account_tracker.cc(264)] OnOAuthError [09:25:51:INFO] [0524/092551.666976:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [09:25:51:INFO] [0524/092551.777045:WARNING:session_sync_bridge.cc(227)] Dropping modification to local session. [09:25:53:INFO] [0524/092553.808118:INFO:signin_manager.cc(122)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [09:25:53:INFO] [0524/092553.905873:ERROR:gcm_driver_desktop.cc(978)] Unable to delete the InstanceID token: cannot start the GCM Client [09:25:53:INFO] [0524/092553.906038:ERROR:gcm_driver_desktop.cc(1062)] Unable to remove the InstanceID data: cannot start the GCM Client [09:25:56:INFO] Test Case '-[SigninSettingsTestCase testSignInPromoWithWarmStateUsingSecondaryButton]' passed (15.385 seconds). [09:25:56:INFO] Test Suite 'SigninSettingsTestCase' passed at 2019-05-24 09:25:56.890. [09:25:56:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 65.580 (65.593) seconds [09:25:56:INFO] Test Suite 'TranslateUITestCase' started at 2019-05-24 09:25:56.890 [09:25:56:INFO] [0524/092556.891475:WARNING:loopback_server.cc(835)] Loopback sync persistent state file does not exist. [09:25:56:INFO] Test Case '-[TranslateUITestCase testAccessibilityOfTranslateSettings]' started. [09:26:11:INFO] [0524/092611.890990:WARNING:gaia_auth_fetcher.cc(878)] Could not reach Google Accounts servers: errno -12 [09:26:16:INFO] Test Case '-[TranslateUITestCase testAccessibilityOfTranslateSettings]' passed (19.863 seconds). [09:26:16:INFO] Test Suite 'TranslateUITestCase' passed at 2019-05-24 09:26:16.764. [09:26:16:INFO] Executed 1 test, with 0 failures (0 unexpected) in 19.863 (19.874) seconds [09:26:16:INFO] Test Suite 'ios_chrome_unified_consent_egtests.app' passed at 2019-05-24 09:26:16.765. [09:26:16:INFO] Executed 138 tests, with 0 failures (0 unexpected) in 2422.686 (2425.274) seconds [09:26:16:INFO] Test Suite 'All tests' passed at 2019-05-24 09:26:16.766. [09:26:16:INFO] Executed 138 tests, with 0 failures (0 unexpected) in 2422.686 (2425.276) seconds [09:26:17:INFO] [09:26:17:INFO] [09:26:17:INFO] Test session results and logs: [09:26:17:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-ahknwillfnssgtasoakegvpjogau/Logs/Test/Test-Transient Testing-2019.05.24_08-45-21--0700.xcresult [09:26:17:INFO] [09:26:17:INFO] 2019-05-24 09:26:17.262 xcodebuild[26924:431674] [MT] IDETestOperationsObserverDebug: 2455.982 elapsed -- Testing started completed. [09:26:17:INFO] 2019-05-24 09:26:17.262 xcodebuild[26924:431674] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [09:26:17:INFO] 2019-05-24 09:26:17.262 xcodebuild[26924:431674] [MT] IDETestOperationsObserverDebug: 2455.982 sec, +2455.982 sec -- end [09:26:17:INFO] ** TEST EXECUTE SUCCEEDED ** [09:26:17:INFO] [09:26:23:DEBUG] Finished print_process_output. [09:26:23:INFO] Waiting for test process to terminate. [09:26:23:INFO] Test process terminated. [09:26:23:DEBUG] Setting sigterm handler. [09:26:23:DEBUG] Stdout flushed after test process. [09:26:23:DEBUG] Processing test results. [09:26:23:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0 [09:26:23:DEBUG] Extracting test data. [09:26:23:DEBUG] Retrieving crash reports. [09:26:23:DEBUG] Retrieving derived data. [09:26:23:DEBUG] Making desktop screenshots. [09:26:23:DEBUG] Killing simulators. [09:26:23:INFO] Killing simulators. [09:26:29:DEBUG] Wiping simulator. Device wiped. [09:26:30:DEBUG] End of tear_down. 2019-05-24 09:27:53,654 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/15r3TsDz/450355aaf136fc10'] 2019-05-24 09:27:53,655 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/15r3TsDz/450355aaf136fc10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/15r3TsDz/450355aaf136fc10/output.json'] 2019-05-24 09:27:53,655 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/15r3TsDz' Found ['/b/s/w/ir/k/recipe_cleanup/15r3TsDz/450355aaf136fc10'] 2019-05-24 09:27:53,655 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/15r3TsDz/450355aaf136fc10/output.json'] 2019-05-24 09:27:53,655 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpU5FWXN.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/15r3TsDz -o /b/s/w/ir/tmp/t/tmpKZXt0a.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpU5FWXN.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/15r3TsDz', '-o', '/b/s/w/ir/tmp/t/tmpKZXt0a.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpU5FWXN.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/15r3TsDz', '-o', '/b/s/w/ir/tmp/t/tmpKZXt0a.json'] returned exit code 0