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

{ "shards": [ { "bot_dimensions": [ { "key": "caches", "value": [ "swarming_module_cache_vpython", "xcode_ios_10b61", "xcode_ios_10l232m", "xcode_ios_10o45e" ] }, { "key": "cores", "value": [ "8" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-E5-2697_v2" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "none" ] }, { "key": "id", "value": [ "vm1130-m4" ] }, { "key": "mac_model", "value": [ "VMware7,1" ] }, { "key": "machine_type", "value": [ "n1-highcpu-8" ] }, { "key": "os", "value": [ "Mac", "Mac-10.13", "Mac-10.13.6" ] }, { "key": "pool", "value": [ "Chrome" ] }, { "key": "python", "value": [ "2.7.10" ] }, { "key": "server_version", "value": [ "4211-90dafba" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-golo", "us-golo-4" ] } ], "bot_id": "vm1130-m4", "bot_version": "a1949c9c9626232734f8c6a011db49db86c0d07bdcf5444a07f04046abeaa3cd", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/mac-amd64", "version": "1vQ99wQk82cRyVYu18rqMpXtb7XZ3WHvL3HFXTcLHN4C" }, "packages": [ { "package_name": "infra/tools/mac_toolchain/mac-amd64", "path": ".", "version": "1065f97353165dfafa89b64bfdaf9b424d705bc7" }, { "package_name": "infra/python/cpython/mac-amd64", "path": ".swarming_module", "version": "6dd10e31dc5d4cbb3c8f42a6fbd9485aeeb9ef0c" }, { "package_name": "infra/tools/luci/logdog/butler/mac-amd64", "path": ".swarming_module", "version": "3138aa2054ab30eac6f7e1e589e590571ae44692" }, { "package_name": "infra/tools/luci/vpython-native/mac-amd64", "path": ".swarming_module", "version": "LH2D9eSe-sJImw8pxSsKdnKz9LueOAM77FFBJMvP8BwC" }, { "package_name": "infra/tools/luci/vpython/mac-amd64", "path": ".swarming_module", "version": "yhDQeJa8hj64DkPAcLKVNQmNTgYsirl5_wvmnHvOz9cC" } ] }, "completed_ts": "2019-03-20T06:07:21.921702", "costs_usd": [ 0.21764320136361645 ], "created_ts": "2019-03-20T05:50:49.686304", "duration": 907.4887759685516, "modified_ts": "2019-03-20T06:07:21.921702", "name": "ios_chrome_integration_egtests (iPhone 7 iOS 11.4)/Mac-10.13.6/693e1b9633/ios12-sdk-simulator/6694", "output": "[I 2019-03-19 22:51:09] About to install Xcode 10b61 in Xcode.app for ios\n[10:51:11:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\n[10:51:20:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/iokbG54u/run_1553061080.xctestrun -destination platform=iOS Simulator,OS=11.4,name=iPhone 7 -resultBundlePath /b/s/w/iokbG54u/11.4 iPhone 7/attempt_0]\n[10:51:22:INFO] User defaults from command line:\nErasing all simulators.\nLaunching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/iokbG54u/run_1553061080.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPhone 7', '-resultBundlePath', '/b/s/w/iokbG54u/11.4 iPhone 7/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itzQId6Z', 'LOGNAME': 'chrome-bot', 'USER': 'chrome-bot', 'HOME': '/Users/chrome-bot', 'BOTO_CONFIG': '/Users/chrome-bot/.boto', 'PATH': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90/bin:/b/s/w/ir/.swarming_module:/b/s/w/ir/.swarming_module/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', 'SWARMING_BOT_ID': 'vm1130-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5EHEm9NFCa/Render', 'SWARMING_SERVER': 'https://chromium-swarm.appspot.com', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'SWARMING_HEADLESS': '1', 'PYTHONNOUSERSITE': '1', 'LUCI_CONTEXT': '/b/s/w/itzQId6Z/luci_ctx.IYjB6V.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6ys4lsnCn9/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43b2787d99b43811', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itzQId6Z', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'}\n[10:51:22:INFO] IDEBuildOperationResultBundlePath = /b/s/w/iokbG54u/11.4 iPhone 7/attempt_0\n[10:51:22:INFO] IDETestRunSpecificationPath = /b/s/w/iokbG54u/run_1553061080.xctestrun\n[10:51:22:INFO] \n[10:51:23:INFO] Testing started on 'iPhone 7'\n[10:51:24:INFO] 2019-03-19 22:51:24.196 xcodebuild[3096:2950355] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[10:51:24:INFO] /b/s/w/iokbG54u/11.4 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-BEB590A9-8D77-4E04-AF6E-E94D4A72B3BA/ios_chrome_integration_egtests_module-30AE3F6D-12E0-46E0-BACA-D4A70D2C7D46/Session-ios_chrome_integration_egtests_module-2019-03-19_225124-jZNgtB.log\n[10:51:24:INFO] 2019-03-19 22:51:24.196 xcodebuild[3096:2950336] [MT] IDETestOperationsObserverDebug: (E9C54B19-3CC1-43D7-A77C-9F01A2FF4A14) Beginning test session ios_chrome_integration_egtests_module-E9C54B19-3CC1-43D7-A77C-9F01A2FF4A14 at 2019-03-19 22:51:24.196 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fd27921fc50> {\n[10:51:24:INFO] \t\tSimDevice: iPhone 7 (3FED26ED-AACE-4F19-8DC3-08188C5565CB, iOS 11.4, Shutdown)\n[10:51:24:INFO] } (11.4 (15F79))\n[10:51:52:INFO] 2019-03-19 22:51:52.464 ios_chrome_integration_egtests[3216:2951920] Crash handler setup started.\n[10:51:52:INFO] 2019-03-19 22:51:52.464 ios_chrome_integration_egtests[3216:2951920] Crash handler setup completed.\n[10:51:52:INFO] 2019-03-19 22:51:52.464 ios_chrome_integration_egtests[3216:2951920] Enabling accessibility for automation on Simulator.\n[10:51:52:INFO] objc[3216]: 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 (0x141c204d0) 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 (0x141a36e38). One of the two will be used. Which one is undefined.\n[10:51:56:INFO] 2019-03-19 22:51:56.148 ios_chrome_integration_egtests[3216:2951920] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fe5ef062e00>.\n[10:51:56:INFO] [0319/225156.195998:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/3FED26ED-AACE-4F19-8DC3-08188C5565CB/data/Containers/Data/Application/279A5440-A463-45E7-9869-F453CFC41F3D/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/3FED26ED-AACE-4F19-8DC3-08188C5565CB/data/Containers/Data/Application/279A5440-A463-45E7-9869-F453CFC41F3D/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[10:52:03:INFO] Test Suite 'All tests' started at 2019-03-19 22:52:03.404\n[10:52:03:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-19 22:52:03.406\n[10:52:03:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-19 22:52:03.407\n[10:52:03:INFO] [0319/225203.415542:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:52:05:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[10:52:09:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (3.801 seconds).\n[10:52:09:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-19 22:52:09.093.\n[10:52:09:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 3.801 (5.686) seconds\n[10:52:09:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-19 22:52:09.094\n[10:52:09:INFO] [0319/225209.095979:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:52:09:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[10:52:11:INFO] [0319/225211.346222:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:17:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (8.492 seconds).\n[10:52:17:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[10:52:19:INFO] [0319/225219.697561:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:25:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (8.303 seconds).\n[10:52:25:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[10:52:28:INFO] [0319/225228.060161:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:36:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.239 seconds).\n[10:52:36:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[10:52:38:INFO] [0319/225238.245478:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:44:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.123 seconds).\n[10:52:44:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[10:52:46:INFO] [0319/225246.446191:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:54:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (10.344 seconds).\n[10:52:54:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[10:52:56:INFO] [0319/225256.647669:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:00:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (6.251 seconds).\n[10:53:00:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[10:53:02:INFO] [0319/225302.966228:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:11:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (11.104 seconds).\n[10:53:11:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[10:53:14:INFO] [0319/225314.013067:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:20:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.054 seconds).\n[10:53:20:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[10:53:22:INFO] [0319/225322.014691:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:30:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (10.124 seconds).\n[10:53:30:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-19 22:53:30.142.\n[10:53:30:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 81.034 (81.048) seconds\n[10:53:30:INFO] Test Suite 'CookiesTestCase' started at 2019-03-19 22:53:30.143\n[10:53:30:INFO] [0319/225330.144493:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:53:30:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[10:53:32:INFO] [0319/225332.296215:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:47:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.234 seconds).\n[10:53:47:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[10:54:01:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.140 seconds).\n[10:54:01:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[10:54:10:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.537 seconds).\n[10:54:10:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[10:54:23:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.410 seconds).\n[10:54:23:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[10:54:37:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (13.941 seconds).\n[10:54:37:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-19 22:54:37.412.\n[10:54:37:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 67.261 (67.269) seconds\n[10:54:37:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-19 22:54:37.413\n[10:54:37:INFO] [0319/225437.415096:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:54:37:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[10:54:39:INFO] 2019-03-19 22:54:39.037 ios_chrome_integration_egtests[3216:2951920] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[10:54:40:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.501 seconds).\n[10:54:40:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[10:54:42:INFO] 2019-03-19 22:54:42.740 ios_chrome_integration_egtests[3216:2951920] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[10:54:44:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.696 seconds).\n[10:54:44:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-19 22:54:44.620.\n[10:54:44:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 7.197 (7.207) seconds\n[10:54:44:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-19 22:54:44.621\n[10:54:44:INFO] [0319/225444.622435:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:54:44:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[10:54:54:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.701 seconds).\n[10:54:54:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[10:55:03:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (9.548 seconds).\n[10:55:03:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[10:55:09:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.754 seconds).\n[10:55:09:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[10:55:13:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.592 seconds).\n[10:55:13:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[10:55:22:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.353 seconds).\n[10:55:22:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-19 22:55:22.577.\n[10:55:22:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 37.947 (37.956) seconds\n[10:55:22:INFO] Test Suite 'FormInputTestCase' started at 2019-03-19 22:55:22.577\n[10:55:22:INFO] [0319/225522.579005:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:55:22:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[10:55:26:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.750 seconds).\n[10:55:26:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[10:55:28:INFO] [0319/225528.407020:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:55:29:INFO] 2019-03-19 22:55:29.962 ios_chrome_integration_egtests[3216:2951920] API error: <_UIKBCompatInputView: 0x7fe5eaeb8930; frame = (0 0; 0 0); layer = <CALayer: 0x61c0002373c0>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[10:55:29:INFO] 2019-03-19 22:55:29.962 ios_chrome_integration_egtests[3216:2951920] API error: <_UIKBCompatInputView: 0x7fe5eaeb8930; frame = (0 0; 0 0); layer = <CALayer: 0x61c0002373c0>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[10:55:34:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (8.657 seconds).\n[10:55:34:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-19 22:55:34.990.\n[10:55:34:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 12.407 (12.413) seconds\n[10:55:34:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-19 22:55:34.992\n[10:55:34:INFO] [0319/225534.993414:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:55:34:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[10:55:37:INFO] [0319/225537.146160:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:55:46:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (11.103 seconds).\n[10:55:46:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[10:55:49:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.709 seconds).\n[10:55:49:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[10:56:00:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.237 seconds).\n[10:56:00:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[10:56:05:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.989 seconds).\n[10:56:05:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[10:56:12:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.546 seconds).\n[10:56:12:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[10:56:18:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.016 seconds).\n[10:56:18:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-19 22:56:18.601.\n[10:56:18:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 43.599 (43.609) seconds\n[10:56:18:INFO] Test Suite 'NTPTilesTest' started at 2019-03-19 22:56:18.601\n[10:56:18:INFO] [0319/225618.603122:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:56:18:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[10:56:22:INFO] [0319/225622.300078:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:56:28:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.421 seconds).\n[10:56:28:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[10:56:51:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (23.353 seconds).\n[10:56:51:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-19 22:56:51.384.\n[10:56:51:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 32.774 (32.782) seconds\n[10:56:51:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-19 22:56:51.384\n[10:56:51:INFO] [0319/225651.385883:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:56:51:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[10:56:53:INFO] [0319/225653.533687:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:57:00:INFO] 2019-03-19 22:57:00.683 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 1.\n[10:57:00:INFO] 2019-03-19 22:57:00.787 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, numbers.\n[10:57:00:INFO] 2019-03-19 22:57:00.874 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 1.\n[10:57:00:INFO] 2019-03-19 22:57:00.980 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 2.\n[10:57:00:INFO] 2019-03-19 22:57:00.981 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 2.\n[10:57:01:INFO] 2019-03-19 22:57:01.051 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 7.\n[10:57:01:INFO] 2019-03-19 22:57:01.052 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 7.\n[10:57:01:INFO] 2019-03-19 22:57:01.123 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:01:INFO] 2019-03-19 22:57:01.129 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:01:INFO] [0319/225701.180852:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 5\n[10:57:01:INFO] 2019-03-19 22:57:01.198 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 0.\n[10:57:01:INFO] 2019-03-19 22:57:01.199 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 0.\n[10:57:01:INFO] 2019-03-19 22:57:01.264 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:01:INFO] 2019-03-19 22:57:01.265 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:01:INFO] 2019-03-19 22:57:01.333 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 0.\n[10:57:01:INFO] 2019-03-19 22:57:01.336 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 0.\n[10:57:01:INFO] [0319/225701.378779:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 9\n[10:57:01:INFO] 2019-03-19 22:57:01.400 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:01:INFO] 2019-03-19 22:57:01.402 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:01:INFO] 2019-03-19 22:57:01.467 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 1.\n[10:57:01:INFO] 2019-03-19 22:57:01.467 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 1.\n[10:57:01:INFO] 2019-03-19 22:57:01.536 ios_chrome_integration_egtests[3216:2951920] Attempting to type key :.\n[10:57:01:INFO] 2019-03-19 22:57:01.539 ios_chrome_integration_egtests[3216:2951920] Tapping on key: :.\n[10:57:01:INFO] 2019-03-19 22:57:01.610 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 5.\n[10:57:01:INFO] 2019-03-19 22:57:01.611 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 5.\n[10:57:01:INFO] 2019-03-19 22:57:01.683 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 1.\n[10:57:01:INFO] 2019-03-19 22:57:01.683 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 1.\n[10:57:01:INFO] 2019-03-19 22:57:01.752 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 3.\n[10:57:01:INFO] 2019-03-19 22:57:01.753 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 3.\n[10:57:01:INFO] 2019-03-19 22:57:01.822 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 5.\n[10:57:01:INFO] 2019-03-19 22:57:01.823 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 5.\n[10:57:01:INFO] 2019-03-19 22:57:01.892 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 7.\n[10:57:01:INFO] 2019-03-19 22:57:01.892 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 7.\n[10:57:01:INFO] 2019-03-19 22:57:01.957 ios_chrome_integration_egtests[3216:2951920] Attempting to type key /.\n[10:57:01:INFO] 2019-03-19 22:57:01.958 ios_chrome_integration_egtests[3216:2951920] Tapping on key: /.\n[10:57:02:INFO] 2019-03-19 22:57:02.020 ios_chrome_integration_egtests[3216:2951920] Attempting to type key t.\n[10:57:02:INFO] 2019-03-19 22:57:02.327 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, letters.\n[10:57:02:INFO] 2019-03-19 22:57:02.380 ios_chrome_integration_egtests[3216:2951920] Tapping on key: t.\n[10:57:02:INFO] 2019-03-19 22:57:02.454 ios_chrome_integration_egtests[3216:2951920] Attempting to type key e.\n[10:57:02:INFO] 2019-03-19 22:57:02.455 ios_chrome_integration_egtests[3216:2951920] Tapping on key: e.\n[10:57:02:INFO] 2019-03-19 22:57:02.518 ios_chrome_integration_egtests[3216:2951920] Attempting to type key s.\n[10:57:02:INFO] 2019-03-19 22:57:02.520 ios_chrome_integration_egtests[3216:2951920] Tapping on key: s.\n[10:57:02:INFO] 2019-03-19 22:57:02.583 ios_chrome_integration_egtests[3216:2951920] Attempting to type key t.\n[10:57:02:INFO] 2019-03-19 22:57:02.584 ios_chrome_integration_egtests[3216:2951920] Tapping on key: t.\n[10:57:02:INFO] 2019-03-19 22:57:02.647 ios_chrome_integration_egtests[3216:2951920] Attempting to type key -.\n[10:57:02:INFO] 2019-03-19 22:57:02.749 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, numbers.\n[10:57:02:INFO] 2019-03-19 22:57:02.804 ios_chrome_integration_egtests[3216:2951920] Tapping on key: -.\n[10:57:02:INFO] 2019-03-19 22:57:02.873 ios_chrome_integration_egtests[3216:2951920] Attempting to type key p.\n[10:57:03:INFO] 2019-03-19 22:57:03.181 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, letters.\n[10:57:03:INFO] 2019-03-19 22:57:03.233 ios_chrome_integration_egtests[3216:2951920] Tapping on key: p.\n[10:57:03:INFO] 2019-03-19 22:57:03.297 ios_chrome_integration_egtests[3216:2951920] Attempting to type key a.\n[10:57:03:INFO] 2019-03-19 22:57:03.298 ios_chrome_integration_egtests[3216:2951920] Tapping on key: a.\n[10:57:03:INFO] 2019-03-19 22:57:03.358 ios_chrome_integration_egtests[3216:2951920] Attempting to type key g.\n[10:57:03:INFO] 2019-03-19 22:57:03.359 ios_chrome_integration_egtests[3216:2951920] Tapping on key: g.\n[10:57:03:INFO] 2019-03-19 22:57:03.422 ios_chrome_integration_egtests[3216:2951920] Attempting to type key e.\n[10:57:03:INFO] 2019-03-19 22:57:03.422 ios_chrome_integration_egtests[3216:2951920] Tapping on key: e.\n[10:57:03:INFO] 2019-03-19 22:57:03.486 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:03:INFO] 2019-03-19 22:57:03.489 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:03:INFO] 2019-03-19 22:57:03.553 ios_chrome_integration_egtests[3216:2951920] Attempting to type key h.\n[10:57:03:INFO] 2019-03-19 22:57:03.554 ios_chrome_integration_egtests[3216:2951920] Tapping on key: h.\n[10:57:03:INFO] 2019-03-19 22:57:03.617 ios_chrome_integration_egtests[3216:2951920] Attempting to type key t.\n[10:57:03:INFO] 2019-03-19 22:57:03.617 ios_chrome_integration_egtests[3216:2951920] Tapping on key: t.\n[10:57:03:INFO] 2019-03-19 22:57:03.678 ios_chrome_integration_egtests[3216:2951920] Attempting to type key m.\n[10:57:03:INFO] 2019-03-19 22:57:03.679 ios_chrome_integration_egtests[3216:2951920] Tapping on key: m.\n[10:57:03:INFO] 2019-03-19 22:57:03.741 ios_chrome_integration_egtests[3216:2951920] Attempting to type key l.\n[10:57:03:INFO] 2019-03-19 22:57:03.742 ios_chrome_integration_egtests[3216:2951920] Tapping on key: l.\n[10:57:03:INFO] 2019-03-19 22:57:03.800 ios_chrome_integration_egtests[3216:2951920] Attempting to type key\n[10:57:03:INFO] .\n[10:57:03:INFO] 2019-03-19 22:57:03.802 ios_chrome_integration_egtests[3216:2951920] Tapping on key: Go.\n[10:57:10:INFO] 2019-03-19 22:57:10.113 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 1.\n[10:57:10:INFO] 2019-03-19 22:57:10.216 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, numbers.\n[10:57:10:INFO] 2019-03-19 22:57:10.268 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 1.\n[10:57:10:INFO] 2019-03-19 22:57:10.363 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 2.\n[10:57:10:INFO] 2019-03-19 22:57:10.364 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 2.\n[10:57:10:INFO] 2019-03-19 22:57:10.442 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 7.\n[10:57:10:INFO] 2019-03-19 22:57:10.443 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 7.\n[10:57:10:INFO] 2019-03-19 22:57:10.517 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:10:INFO] 2019-03-19 22:57:10.519 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:10:INFO] [0319/225710.581732:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 21\n[10:57:10:INFO] 2019-03-19 22:57:10.595 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 0.\n[10:57:10:INFO] 2019-03-19 22:57:10.596 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 0.\n[10:57:10:INFO] 2019-03-19 22:57:10.670 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:10:INFO] 2019-03-19 22:57:10.672 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:10:INFO] 2019-03-19 22:57:10.748 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 0.\n[10:57:10:INFO] 2019-03-19 22:57:10.749 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 0.\n[10:57:10:INFO] 2019-03-19 22:57:10.824 ios_chrome_integration_egtests[3216:2951920] Attempting to type key ..\n[10:57:10:INFO] 2019-03-19 22:57:10.825 ios_chrome_integration_egtests[3216:2951920] Tapping on key: ..\n[10:57:10:INFO] 2019-03-19 22:57:10.897 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 1.\n[10:57:10:INFO] 2019-03-19 22:57:10.898 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 1.\n[10:57:10:INFO] 2019-03-19 22:57:10.973 ios_chrome_integration_egtests[3216:2951920] Attempting to type key :.\n[10:57:10:INFO] 2019-03-19 22:57:10.975 ios_chrome_integration_egtests[3216:2951920] Tapping on key: :.\n[10:57:11:INFO] 2019-03-19 22:57:11.048 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 5.\n[10:57:11:INFO] 2019-03-19 22:57:11.049 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 5.\n[10:57:11:INFO] 2019-03-19 22:57:11.122 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 1.\n[10:57:11:INFO] 2019-03-19 22:57:11.122 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 1.\n[10:57:11:INFO] 2019-03-19 22:57:11.195 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 3.\n[10:57:11:INFO] 2019-03-19 22:57:11.196 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 3.\n[10:57:11:INFO] 2019-03-19 22:57:11.280 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 5.\n[10:57:11:INFO] 2019-03-19 22:57:11.282 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 5.\n[10:57:11:INFO] 2019-03-19 22:57:11.356 ios_chrome_integration_egtests[3216:2951920] Attempting to type key 7.\n[10:57:11:INFO] 2019-03-19 22:57:11.357 ios_chrome_integration_egtests[3216:2951920] Tapping on key: 7.\n[10:57:11:INFO] 2019-03-19 22:57:11.427 ios_chrome_integration_egtests[3216:2951920] Attempting to type key /.\n[10:57:11:INFO] 2019-03-19 22:57:11.428 ios_chrome_integration_egtests[3216:2951920] Tapping on key: /.\n[10:57:11:INFO] 2019-03-19 22:57:11.492 ios_chrome_integration_egtests[3216:2951920] Attempting to type key t.\n[10:57:11:INFO] 2019-03-19 22:57:11.799 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, letters.\n[10:57:11:INFO] 2019-03-19 22:57:11.853 ios_chrome_integration_egtests[3216:2951920] Tapping on key: t.\n[10:57:11:INFO] 2019-03-19 22:57:11.919 ios_chrome_integration_egtests[3216:2951920] Attempting to type key e.\n[10:57:11:INFO] 2019-03-19 22:57:11.919 ios_chrome_integration_egtests[3216:2951920] Tapping on key: e.\n[10:57:11:INFO] 2019-03-19 22:57:11.985 ios_chrome_integration_egtests[3216:2951920] Attempting to type key s.\n[10:57:11:INFO] 2019-03-19 22:57:11.987 ios_chrome_integration_egtests[3216:2951920] Tapping on key: s.\n[10:57:12:INFO] 2019-03-19 22:57:12.053 ios_chrome_integration_egtests[3216:2951920] Attempting to type key t.\n[10:57:12:INFO] 2019-03-19 22:57:12.053 ios_chrome_integration_egtests[3216:2951920] Tapping on key: t.\n[10:57:12:INFO] 2019-03-19 22:57:12.119 ios_chrome_integration_egtests[3216:2951920] Attempting to type key -.\n[10:57:12:INFO] 2019-03-19 22:57:12.222 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, numbers.\n[10:57:12:INFO] 2019-03-19 22:57:12.276 ios_chrome_integration_egtests[3216:2951920] Tapping on key: -.\n[10:57:12:INFO] 2019-03-19 22:57:12.348 ios_chrome_integration_egtests[3216:2951920] Attempting to type key p.\n[10:57:12:INFO] 2019-03-19 22:57:12.655 ios_chrome_integration_egtests[3216:2951920] Tapping on key: more, letters.\n[10:57:12:INFO] 2019-03-19 22:57:12.709 ios_chrome_integration_egtests[3216:2951920] Tapping on key: p.\n[10:57:12:INFO] 2019-03-19 22:57:12.775 ios_chrome_integration_egtests[3216:2951920] Attempting to type key a.\n[10:57:12:INFO] 2019-03-19 22:57:12.776 ios_chrome_integration_egtests[3216:2951920] Tapping on key: a.\n[10:57:12:INFO] 2019-03-19 22:57:12.839 ios_chrome_integration_egtests[3216:2951920] Attempting to type key g.\n[10:57:12:INFO] 2019-03-19 22:57:12.840 ios_chrome_integration_egtests[3216:2951920] Tapping on key: g.\n[10:57:17:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (25.858 seconds).\n[10:57:17:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-19 22:57:17.251.\n[10:57:17:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 25.858 (25.867) seconds\n[10:57:17:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-19 22:57:17.252\n[10:57:17:INFO] [0319/225717.253391:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:57:17:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[10:57:20:INFO] [0319/225720.851432:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:57:35:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (18.215 seconds).\n[10:57:35:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[10:58:05:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (29.606 seconds).\n[10:58:05:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[10:58:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.059 seconds).\n[10:58:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[10:58:43:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (21.412 seconds).\n[10:58:43:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[10:58:57:INFO] [0319/225857.990475:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:58:INFO] [0319/225858.378410:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:01:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (17.882 seconds).\n[10:59:01:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[10:59:09:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (8.277 seconds).\n[10:59:09:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[10:59:16:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.110 seconds).\n[10:59:16:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[10:59:27:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (10.310 seconds).\n[10:59:27:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[10:59:58:INFO] [0319/225958.872326:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:59:INFO] [0319/225959.292955:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:02:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (35.730 seconds).\n[11:00:02:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[11:00:16:INFO] [0319/230016.949782:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:17:INFO] [0319/230017.344903:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:20:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (17.568 seconds).\n[11:00:20:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[11:00:35:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.148 seconds).\n[11:00:35:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-19 23:00:35.582.\n[11:00:35:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 198.318 (198.330) seconds\n[11:00:35:INFO] Test Suite 'UKMTestCase' started at 2019-03-19 23:00:35.583\n[11:00:35:INFO] [0319/230035.584533:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:00:35:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[11:00:43:INFO] 2019-03-19 23:00:43.161 ios_chrome_integration_egtests[3216:2951920] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fe5f6075940; baseClass = UIButton; frame = (16 615; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x6100004e9200>>, Touch Target: {86, 36}\n[11:00:44:INFO] [0319/230044.599247:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:00:44:INFO] [0319/230044.599425:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:00:44:INFO] [0319/230044.600175:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:00:44:INFO] [0319/230044.627971:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:00:45:INFO] [0319/230045.665539:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:00:57:INFO] [0319/230057.590252:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:08:INFO] [0319/230108.578282:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:01:08:INFO] [0319/230108.578492:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:01:09:INFO] [0319/230109.667701:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:01:09:INFO] [0319/230109.778428:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[11:01:21:INFO] [0319/230121.525369:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:25:INFO] [0319/230125.457211:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:01:27:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (51.827 seconds).\n[11:01:27:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[11:01:36:INFO] [0319/230136.709664:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:36:INFO] [0319/230136.712141:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:36:INFO] [0319/230136.725605:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:01:36:INFO] [0319/230136.725788:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:01:37:INFO] [0319/230137.770982:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:01:55:INFO] [0319/230155.523979:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:59:INFO] [0319/230159.555081:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:02:01:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (34.094 seconds).\n[11:02:01:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[11:02:10:INFO] [0319/230210.936700:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:10:INFO] [0319/230210.939472:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:10:INFO] [0319/230210.948738:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:02:10:INFO] [0319/230210.948989:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:02:12:INFO] [0319/230212.003859:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:02:24:INFO] [0319/230224.201144:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:02:28:INFO] [0319/230228.248308:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:02:30:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (28.664 seconds).\n[11:02:30:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[11:02:39:INFO] [0319/230239.418396:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:39:INFO] [0319/230239.420415:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:39:INFO] [0319/230239.448266:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:02:39:INFO] [0319/230239.448465:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:02:40:INFO] [0319/230240.530907:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:02:INFO] [0319/230302.438578:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:03:06:INFO] [0319/230306.386047:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:03:08:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (38.194 seconds).\n[11:03:08:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[11:03:17:INFO] [0319/230317.623898:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:17:INFO] [0319/230317.626325:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:17:INFO] [0319/230317.644564:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:03:17:INFO] [0319/230317.644719:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:03:18:INFO] [0319/230318.720143:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:53:INFO] [0319/230353.475155:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:03:57:INFO] [0319/230357.433335:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:04:04:INFO] [0319/230404.479168:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:04:INFO] [0319/230404.480812:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:04:INFO] [0319/230404.487141:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:04:04:INFO] [0319/230404.487314:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:04:05:INFO] [0319/230405.590731:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:17:INFO] [0319/230417.746194:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:04:21:INFO] [0319/230421.718264:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:04:23:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (75.304 seconds).\n[11:04:23:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[11:04:33:INFO] [0319/230433.032705:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:33:INFO] [0319/230433.035016:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:33:INFO] [0319/230433.043456:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:04:33:INFO] [0319/230433.043641:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:04:34:INFO] [0319/230434.134699:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:48:INFO] [0319/230448.635230:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:50:INFO] [0319/230450.213511:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:51:INFO] [0319/230451.739735:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:53:INFO] [0319/230453.245608:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:05:03:INFO] [0319/230503.778819:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:05:07:INFO] [0319/230507.793103:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:05:09:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (46.048 seconds).\n[11:05:09:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[11:05:19:INFO] [0319/230519.068129:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:05:19:INFO] [0319/230519.070740:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:05:19:INFO] [0319/230519.081354:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:05:19:INFO] [0319/230519.081547:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:05:20:INFO] [0319/230520.185933:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:05:32:INFO] [0319/230532.145265:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:05:43:INFO] [0319/230543.141994:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:05:43:INFO] [0319/230543.142230:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:05:44:INFO] [0319/230544.221131:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:05:44:INFO] [0319/230544.334379:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[11:05:56:INFO] [0319/230556.203217:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:06:00:INFO] [0319/230600.139345:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:06:02:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (52.363 seconds).\n[11:06:02:INFO] Test Suite 'UKMTestCase' passed at 2019-03-19 23:06:02.087.\n[11:06:02:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 326.493 (326.504) seconds\n[11:06:02:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-19 23:06:02.088.\n[11:06:02:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 836.689 (838.682) seconds\n[11:06:02:INFO] Test Suite 'All tests' passed at 2019-03-19 23:06:02.090.\n[11:06:02:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 836.689 (838.685) seconds\n[11:06:02:INFO] \n[11:06:02:INFO] \n[11:06:02:INFO] Test session log:\n[11:06:02:INFO] \t/b/s/w/iokbG54u/11.4 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-BEB590A9-8D77-4E04-AF6E-E94D4A72B3BA/ios_chrome_integration_egtests_module-30AE3F6D-12E0-46E0-BACA-D4A70D2C7D46/Session-ios_chrome_integration_egtests_module-2019-03-19_225124-jZNgtB.log\n[11:06:02:INFO] \n[11:06:02:INFO] 2019-03-19 23:06:02.427 xcodebuild[3096:2950336] [MT] IDETestOperationsObserverDebug: 879.309 elapsed -- Testing started completed.\n[11:06:02:INFO] 2019-03-19 23:06:02.427 xcodebuild[3096:2950336] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[11:06:02:INFO] 2019-03-19 23:06:02.427 xcodebuild[3096:2950336] [MT] IDETestOperationsObserverDebug: 879.309 sec, +879.309 sec -- end\n[11:06:02:INFO] ** TEST EXECUTE SUCCEEDED **\n[11:06:02:INFO] \n[11:06:06:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/iokbG54u/run_1553061080.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPhone 7', '-resultBundlePath', '/b/s/w/iokbG54u/11.4 iPhone 7/attempt_0'] finished with 0\n[11:06:06:DEBUG] Test ended.\n[11:06:06:DEBUG] Extracting test data.\n[11:06:06:DEBUG] Retrieving crash reports.\n[11:06:06:DEBUG] Retrieving derived data.\n[11:06:06:DEBUG] Making desktop screenshots.\n[11:06:06:DEBUG] Killing simulators.\n[11:06:11:DEBUG] Wiping simulator.\nDevice wiped.\n[11:06:15:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "3cb5a250421c690521cae169d24d1ab6ed2cedc9", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43b2787d99b43811", "server_versions": [ "4211-90dafba" ], "started_ts": "2019-03-20T05:50:50.359581", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-sdk-simulator", "buildnumber:6694", "data:693e1b96330e53af365b7790af40241bfc798047", "device_type:iPhone 7", "ios_version:11.4", "master:chromium.fyi", "name:ios_chrome_integration_egtests", "os:Mac-10.13.6", "platform:simulator", "pool:Chrome", "priority:35", "service_account:ios-isolated-tester@chops-service-accounts.iam.gserviceaccount.com", "slavename:build111-m9", "spec_name:chromium.ci:ios12-sdk-simulator", "stepname:ios_chrome_integration_egtests (iPhone 7 iOS 11.4) on Mac-10.13.6", "swarming.pool.template:none", "swarming.pool.version:6c01c2bd5d548ca0b887eb56d59b3a36edd44394", "test:ios_chrome_integration_egtests", "user:None" ], "task_id": "43b2787d99b43810", "try_number": "1" } ] }