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": [ "vm922-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": "vm922-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:06:49.696723", "costs_usd": [ 0.2134551825200977 ], "created_ts": "2019-03-20T05:50:36.780489", "duration": 889.914067029953, "modified_ts": "2019-03-20T06:06:49.696723", "name": "ios_chrome_integration_egtests (iPhone 7 iOS 12.1)/Mac-10.13.6/e1b4a1a4e6/ios12-sdk-simulator/6694", "output": "[I 2019-03-19 22:50:59] About to install Xcode 10b61 in Xcode.app for ios\n[10:51:00:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\n[10:51:13:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioeWbzUw/run_1553061073.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone 7 -resultBundlePath /b/s/w/ioeWbzUw/12.1 iPhone 7/attempt_0]\n[10:51:17:INFO] User defaults from command line:\nErasing all simulators.\nLaunching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioeWbzUw/run_1553061073.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone 7', '-resultBundlePath', '/b/s/w/ioeWbzUw/12.1 iPhone 7/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itnF8fub', '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': 'vm922-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wqig3GZtXc/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/itnF8fub/luci_ctx.f3Ah6Q.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GCo1ejZmYi/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43b2784b080b9211', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itnF8fub', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'}\n[10:51:17:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioeWbzUw/12.1 iPhone 7/attempt_0\n[10:51:17:INFO] IDETestRunSpecificationPath = /b/s/w/ioeWbzUw/run_1553061073.xctestrun\n[10:51:17:INFO] \n[10:51:18:INFO] Testing started on 'iPhone 7'\n[10:51:18:INFO] 2019-03-19 22:51:18.876 xcodebuild[8488:97703] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[10:51:18:INFO] /b/s/w/ioeWbzUw/12.1 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-BCB26B7C-4007-4E9A-86BC-FF7E28236461/ios_chrome_integration_egtests_module-D52BADBB-F19F-424E-A7F0-EFCBFBBD0926/Session-ios_chrome_integration_egtests_module-2019-03-19_225118-fZ7Rnc.log\n[10:51:18:INFO] 2019-03-19 22:51:18.877 xcodebuild[8488:97682] [MT] IDETestOperationsObserverDebug: (2BE4003A-36CA-4866-8C12-B8BD7D3452A2) Beginning test session ios_chrome_integration_egtests_module-2BE4003A-36CA-4866-8C12-B8BD7D3452A2 at 2019-03-19 22:51:18.877 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f8f2a28b0d0> {\n[10:51:18:INFO] \t\tSimDevice: iPhone 7 (5625DC99-CAA6-4B94-81DC-6320DB61CA4A, iOS 12.1, Shutdown)\n[10:51:18:INFO] } (12.1 (16B91))\n[10:51:39:INFO] 2019-03-19 22:51:39.653 ios_chrome_integration_egtests[8613:98963] Crash handler setup started.\n[10:51:39:INFO] 2019-03-19 22:51:39.654 ios_chrome_integration_egtests[8613:98963] Crash handler setup completed.\n[10:51:39:INFO] 2019-03-19 22:51:39.654 ios_chrome_integration_egtests[8613:98963] Enabling accessibility for automation on Simulator.\n[10:51:43:INFO] 2019-03-19 22:51:43.589 ios_chrome_integration_egtests[8613:98963] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f9fbe83fa00>.\n[10:51:43:INFO] [0319/225143.745744:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/5625DC99-CAA6-4B94-81DC-6320DB61CA4A/data/Containers/Data/Application/8A3230AE-7AA9-4944-8B0F-6C35D2F009DF/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/5625DC99-CAA6-4B94-81DC-6320DB61CA4A/data/Containers/Data/Application/8A3230AE-7AA9-4944-8B0F-6C35D2F009DF/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[10:51:50:INFO] Test Suite 'All tests' started at 2019-03-19 22:51:50.957\n[10:51:50:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-19 22:51:50.958\n[10:51:50:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-19 22:51:50.959\n[10:51:50:INFO] [0319/225150.995617:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:51:52:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[10:51:56:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (3.973 seconds).\n[10:51:56:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-19 22:51:56.854.\n[10:51:56:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 3.973 (5.895) seconds\n[10:51:56:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-19 22:51:56.856\n[10:51:56:INFO] [0319/225156.857691:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:51:56:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[10:51:59:INFO] [0319/225159.035661:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:05:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (8.829 seconds).\n[10:52:05:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[10:52:07:INFO] [0319/225207.692212:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:14:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (8.605 seconds).\n[10:52:14:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[10:52:16:INFO] [0319/225216.714118:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:24:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.432 seconds).\n[10:52:24:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[10:52:26:INFO] [0319/225226.594132:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:32:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.015 seconds).\n[10:52:32:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[10:52:34:INFO] [0319/225234.597359:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:42:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (9.436 seconds).\n[10:52:42:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[10:52:44:INFO] [0319/225244.115496:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:48:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (6.013 seconds).\n[10:52:48:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[10:52:50:INFO] [0319/225250.127140:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:59:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (10.933 seconds).\n[10:52:59:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[10:53:00:INFO] [0319/225300.972338:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:07:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.124 seconds).\n[10:53:07:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[10:53:09:INFO] [0319/225309.130197:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:17:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (9.844 seconds).\n[10:53:17:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-19 22:53:17.105.\n[10:53:17:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 80.230 (80.249) seconds\n[10:53:17:INFO] Test Suite 'CookiesTestCase' started at 2019-03-19 22:53:17.106\n[10:53:17:INFO] [0319/225317.107452:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:53:17:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[10:53:19:INFO] [0319/225319.168581:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:53:34:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.367 seconds).\n[10:53:34:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[10:53:48:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.015 seconds).\n[10:53:48:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[10:53:56:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.477 seconds).\n[10:53:56:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[10:54:10:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.057 seconds).\n[10:54:10:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[10:54:23:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (13.917 seconds).\n[10:54:23:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-19 22:54:23.948.\n[10:54:23:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 66.833 (66.842) seconds\n[10:54:23:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-19 22:54:23.948\n[10:54:23:INFO] [0319/225423.949694:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:54:23:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[10:54:25:INFO] 2019-03-19 22:54:25.501 ios_chrome_integration_egtests[8613:98963] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[10:54:27:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.431 seconds).\n[10:54:27:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[10:54:29:INFO] 2019-03-19 22:54:29.138 ios_chrome_integration_egtests[8613:98963] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[10:54:31:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.628 seconds).\n[10:54:31:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-19 22:54:31.014.\n[10:54:31:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 7.059 (7.066) seconds\n[10:54:31:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-19 22:54:31.015\n[10:54:31:INFO] [0319/225431.016265:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:54:31:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[10:54:40:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.688 seconds).\n[10:54:40:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[10:54:50:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (9.518 seconds).\n[10:54:50:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[10:54:56:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.796 seconds).\n[10:54:56:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[10:54:59:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.638 seconds).\n[10:54:59:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[10:55:09:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.457 seconds).\n[10:55:09:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-19 22:55:09.121.\n[10:55:09:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 38.097 (38.106) seconds\n[10:55:09:INFO] Test Suite 'FormInputTestCase' started at 2019-03-19 22:55:09.122\n[10:55:09:INFO] [0319/225509.123335:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:55:09:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[10:55:12:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.765 seconds).\n[10:55:12:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[10:55:14:INFO] [0319/225514.822913:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:55:16:INFO] 2019-03-19 22:55:16.573 ios_chrome_integration_egtests[8613:98963] API error: <_UIKBCompatInputView: 0x7f9fbcfc2bf0; frame = (0 0; 0 0); layer = <CALayer: 0x6000030fd000>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[10:55:16:INFO] 2019-03-19 22:55:16.573 ios_chrome_integration_egtests[8613:98963] API error: <_UIKBCompatInputView: 0x7f9fbcfc2bf0; frame = (0 0; 0 0); layer = <CALayer: 0x6000030fd000>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[10:55:21:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (8.440 seconds).\n[10:55:21:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-19 22:55:21.332.\n[10:55:21:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 12.204 (12.210) seconds\n[10:55:21:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-19 22:55:21.333\n[10:55:21:INFO] [0319/225521.334209:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:55:21:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[10:55:23:INFO] [0319/225523.335100:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:55:32:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (10.999 seconds).\n[10:55:32:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[10:55:36:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.851 seconds).\n[10:55:36:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[10:55:46:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.437 seconds).\n[10:55:46:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[10:55:51:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.878 seconds).\n[10:55:51:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[10:55:59:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.685 seconds).\n[10:55:59:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[10:56:05:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.283 seconds).\n[10:56:05:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-19 22:56:05.476.\n[10:56:05:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 44.133 (44.143) seconds\n[10:56:05:INFO] Test Suite 'NTPTilesTest' started at 2019-03-19 22:56:05.477\n[10:56:05:INFO] [0319/225605.478261:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:56:05:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[10:56:09:INFO] [0319/225609.254123:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:56:15:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.574 seconds).\n[10:56:15:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[10:56:38:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (23.495 seconds).\n[10:56:38:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-19 22:56:38.555.\n[10:56:38:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 33.069 (33.078) seconds\n[10:56:38:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-19 22:56:38.556\n[10:56:38:INFO] [0319/225638.557471:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:56:38:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[10:56:40:INFO] [0319/225640.590431:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:56:47:INFO] 2019-03-19 22:56:47.580 ios_chrome_integration_egtests[8613:98963] Attempting to type key 1.\n[10:56:47:INFO] 2019-03-19 22:56:47.684 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, numbers.\n[10:56:47:INFO] 2019-03-19 22:56:47.770 ios_chrome_integration_egtests[8613:98963] Tapping on key: 1.\n[10:56:47:INFO] 2019-03-19 22:56:47.887 ios_chrome_integration_egtests[8613:98963] Attempting to type key 2.\n[10:56:47:INFO] 2019-03-19 22:56:47.888 ios_chrome_integration_egtests[8613:98963] Tapping on key: 2.\n[10:56:47:INFO] 2019-03-19 22:56:47.957 ios_chrome_integration_egtests[8613:98963] Attempting to type key 7.\n[10:56:47:INFO] 2019-03-19 22:56:47.958 ios_chrome_integration_egtests[8613:98963] Tapping on key: 7.\n[10:56:48:INFO] [0319/225648.005253:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 3\n[10:56:48:INFO] 2019-03-19 22:56:48.028 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:48:INFO] 2019-03-19 22:56:48.030 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:48:INFO] 2019-03-19 22:56:48.103 ios_chrome_integration_egtests[8613:98963] Attempting to type key 0.\n[10:56:48:INFO] 2019-03-19 22:56:48.105 ios_chrome_integration_egtests[8613:98963] Tapping on key: 0.\n[10:56:48:INFO] 2019-03-19 22:56:48.172 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:48:INFO] 2019-03-19 22:56:48.174 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:48:INFO] 2019-03-19 22:56:48.246 ios_chrome_integration_egtests[8613:98963] Attempting to type key 0.\n[10:56:48:INFO] 2019-03-19 22:56:48.247 ios_chrome_integration_egtests[8613:98963] Tapping on key: 0.\n[10:56:48:INFO] 2019-03-19 22:56:48.316 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:48:INFO] 2019-03-19 22:56:48.318 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:48:INFO] 2019-03-19 22:56:48.387 ios_chrome_integration_egtests[8613:98963] Attempting to type key 1.\n[10:56:48:INFO] 2019-03-19 22:56:48.388 ios_chrome_integration_egtests[8613:98963] Tapping on key: 1.\n[10:56:48:INFO] 2019-03-19 22:56:48.459 ios_chrome_integration_egtests[8613:98963] Attempting to type key :.\n[10:56:48:INFO] 2019-03-19 22:56:48.460 ios_chrome_integration_egtests[8613:98963] Tapping on key: :.\n[10:56:48:INFO] 2019-03-19 22:56:48.534 ios_chrome_integration_egtests[8613:98963] Attempting to type key 5.\n[10:56:48:INFO] 2019-03-19 22:56:48.534 ios_chrome_integration_egtests[8613:98963] Tapping on key: 5.\n[10:56:48:INFO] 2019-03-19 22:56:48.606 ios_chrome_integration_egtests[8613:98963] Attempting to type key 1.\n[10:56:48:INFO] 2019-03-19 22:56:48.607 ios_chrome_integration_egtests[8613:98963] Tapping on key: 1.\n[10:56:48:INFO] 2019-03-19 22:56:48.677 ios_chrome_integration_egtests[8613:98963] Attempting to type key 7.\n[10:56:48:INFO] 2019-03-19 22:56:48.677 ios_chrome_integration_egtests[8613:98963] Tapping on key: 7.\n[10:56:48:INFO] 2019-03-19 22:56:48.749 ios_chrome_integration_egtests[8613:98963] Attempting to type key 8.\n[10:56:48:INFO] 2019-03-19 22:56:48.749 ios_chrome_integration_egtests[8613:98963] Tapping on key: 8.\n[10:56:48:INFO] 2019-03-19 22:56:48.840 ios_chrome_integration_egtests[8613:98963] Attempting to type key 2.\n[10:56:48:INFO] 2019-03-19 22:56:48.841 ios_chrome_integration_egtests[8613:98963] Tapping on key: 2.\n[10:56:48:INFO] 2019-03-19 22:56:48.929 ios_chrome_integration_egtests[8613:98963] Attempting to type key /.\n[10:56:48:INFO] 2019-03-19 22:56:48.931 ios_chrome_integration_egtests[8613:98963] Tapping on key: /.\n[10:56:49:INFO] 2019-03-19 22:56:48.999 ios_chrome_integration_egtests[8613:98963] Attempting to type key t.\n[10:56:49:INFO] 2019-03-19 22:56:49.309 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, letters.\n[10:56:49:INFO] 2019-03-19 22:56:49.360 ios_chrome_integration_egtests[8613:98963] Tapping on key: t.\n[10:56:49:INFO] 2019-03-19 22:56:49.424 ios_chrome_integration_egtests[8613:98963] Attempting to type key e.\n[10:56:49:INFO] 2019-03-19 22:56:49.425 ios_chrome_integration_egtests[8613:98963] Tapping on key: e.\n[10:56:49:INFO] 2019-03-19 22:56:49.488 ios_chrome_integration_egtests[8613:98963] Attempting to type key s.\n[10:56:49:INFO] 2019-03-19 22:56:49.490 ios_chrome_integration_egtests[8613:98963] Tapping on key: s.\n[10:56:49:INFO] 2019-03-19 22:56:49.561 ios_chrome_integration_egtests[8613:98963] Attempting to type key t.\n[10:56:49:INFO] 2019-03-19 22:56:49.562 ios_chrome_integration_egtests[8613:98963] Tapping on key: t.\n[10:56:49:INFO] 2019-03-19 22:56:49.635 ios_chrome_integration_egtests[8613:98963] Attempting to type key -.\n[10:56:49:INFO] 2019-03-19 22:56:49.739 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, numbers.\n[10:56:49:INFO] 2019-03-19 22:56:49.810 ios_chrome_integration_egtests[8613:98963] Tapping on key: -.\n[10:56:49:INFO] 2019-03-19 22:56:49.884 ios_chrome_integration_egtests[8613:98963] Attempting to type key p.\n[10:56:50:INFO] 2019-03-19 22:56:50.192 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, letters.\n[10:56:50:INFO] 2019-03-19 22:56:50.244 ios_chrome_integration_egtests[8613:98963] Tapping on key: p.\n[10:56:50:INFO] 2019-03-19 22:56:50.307 ios_chrome_integration_egtests[8613:98963] Attempting to type key a.\n[10:56:50:INFO] 2019-03-19 22:56:50.308 ios_chrome_integration_egtests[8613:98963] Tapping on key: a.\n[10:56:50:INFO] 2019-03-19 22:56:50.371 ios_chrome_integration_egtests[8613:98963] Attempting to type key g.\n[10:56:50:INFO] 2019-03-19 22:56:50.372 ios_chrome_integration_egtests[8613:98963] Tapping on key: g.\n[10:56:50:INFO] 2019-03-19 22:56:50.435 ios_chrome_integration_egtests[8613:98963] Attempting to type key e.\n[10:56:50:INFO] 2019-03-19 22:56:50.435 ios_chrome_integration_egtests[8613:98963] Tapping on key: e.\n[10:56:50:INFO] 2019-03-19 22:56:50.497 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:50:INFO] 2019-03-19 22:56:50.499 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:50:INFO] 2019-03-19 22:56:50.563 ios_chrome_integration_egtests[8613:98963] Attempting to type key h.\n[10:56:50:INFO] 2019-03-19 22:56:50.563 ios_chrome_integration_egtests[8613:98963] Tapping on key: h.\n[10:56:50:INFO] 2019-03-19 22:56:50.652 ios_chrome_integration_egtests[8613:98963] Attempting to type key t.\n[10:56:50:INFO] 2019-03-19 22:56:50.654 ios_chrome_integration_egtests[8613:98963] Tapping on key: t.\n[10:56:50:INFO] 2019-03-19 22:56:50.720 ios_chrome_integration_egtests[8613:98963] Attempting to type key m.\n[10:56:50:INFO] 2019-03-19 22:56:50.721 ios_chrome_integration_egtests[8613:98963] Tapping on key: m.\n[10:56:50:INFO] 2019-03-19 22:56:50.783 ios_chrome_integration_egtests[8613:98963] Attempting to type key l.\n[10:56:50:INFO] 2019-03-19 22:56:50.784 ios_chrome_integration_egtests[8613:98963] Tapping on key: l.\n[10:56:50:INFO] 2019-03-19 22:56:50.843 ios_chrome_integration_egtests[8613:98963] Attempting to type key\n[10:56:50:INFO] .\n[10:56:50:INFO] 2019-03-19 22:56:50.844 ios_chrome_integration_egtests[8613:98963] Tapping on key: Go.\n[10:56:56:INFO] 2019-03-19 22:56:56.876 ios_chrome_integration_egtests[8613:98963] Attempting to type key 1.\n[10:56:56:INFO] 2019-03-19 22:56:56.979 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, numbers.\n[10:56:57:INFO] 2019-03-19 22:56:57.049 ios_chrome_integration_egtests[8613:98963] Tapping on key: 1.\n[10:56:57:INFO] 2019-03-19 22:56:57.143 ios_chrome_integration_egtests[8613:98963] Attempting to type key 2.\n[10:56:57:INFO] 2019-03-19 22:56:57.144 ios_chrome_integration_egtests[8613:98963] Tapping on key: 2.\n[10:56:57:INFO] 2019-03-19 22:56:57.256 ios_chrome_integration_egtests[8613:98963] Attempting to type key 7.\n[10:56:57:INFO] 2019-03-19 22:56:57.258 ios_chrome_integration_egtests[8613:98963] Tapping on key: 7.\n[10:56:57:INFO] 2019-03-19 22:56:57.393 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:57:INFO] 2019-03-19 22:56:57.395 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:57:INFO] 2019-03-19 22:56:57.491 ios_chrome_integration_egtests[8613:98963] Attempting to type key 0.\n[10:56:57:INFO] 2019-03-19 22:56:57.492 ios_chrome_integration_egtests[8613:98963] Tapping on key: 0.\n[10:56:57:INFO] 2019-03-19 22:56:57.586 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:57:INFO] 2019-03-19 22:56:57.590 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:57:INFO] 2019-03-19 22:56:57.684 ios_chrome_integration_egtests[8613:98963] Attempting to type key 0.\n[10:56:57:INFO] 2019-03-19 22:56:57.686 ios_chrome_integration_egtests[8613:98963] Tapping on key: 0.\n[10:56:57:INFO] 2019-03-19 22:56:57.790 ios_chrome_integration_egtests[8613:98963] Attempting to type key ..\n[10:56:57:INFO] 2019-03-19 22:56:57.792 ios_chrome_integration_egtests[8613:98963] Tapping on key: ..\n[10:56:57:INFO] 2019-03-19 22:56:57.890 ios_chrome_integration_egtests[8613:98963] Attempting to type key 1.\n[10:56:57:INFO] 2019-03-19 22:56:57.893 ios_chrome_integration_egtests[8613:98963] Tapping on key: 1.\n[10:56:58:INFO] 2019-03-19 22:56:57.998 ios_chrome_integration_egtests[8613:98963] Attempting to type key :.\n[10:56:58:INFO] 2019-03-19 22:56:58.000 ios_chrome_integration_egtests[8613:98963] Tapping on key: :.\n[10:56:58:INFO] 2019-03-19 22:56:58.097 ios_chrome_integration_egtests[8613:98963] Attempting to type key 5.\n[10:56:58:INFO] 2019-03-19 22:56:58.098 ios_chrome_integration_egtests[8613:98963] Tapping on key: 5.\n[10:56:58:INFO] [0319/225658.146556:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 33\n[10:56:58:INFO] 2019-03-19 22:56:58.185 ios_chrome_integration_egtests[8613:98963] Attempting to type key 1.\n[10:56:58:INFO] 2019-03-19 22:56:58.185 ios_chrome_integration_egtests[8613:98963] Tapping on key: 1.\n[10:56:58:INFO] 2019-03-19 22:56:58.260 ios_chrome_integration_egtests[8613:98963] Attempting to type key 7.\n[10:56:58:INFO] 2019-03-19 22:56:58.260 ios_chrome_integration_egtests[8613:98963] Tapping on key: 7.\n[10:56:58:INFO] 2019-03-19 22:56:58.336 ios_chrome_integration_egtests[8613:98963] Attempting to type key 8.\n[10:56:58:INFO] 2019-03-19 22:56:58.337 ios_chrome_integration_egtests[8613:98963] Tapping on key: 8.\n[10:56:58:INFO] 2019-03-19 22:56:58.412 ios_chrome_integration_egtests[8613:98963] Attempting to type key 2.\n[10:56:58:INFO] 2019-03-19 22:56:58.413 ios_chrome_integration_egtests[8613:98963] Tapping on key: 2.\n[10:56:58:INFO] 2019-03-19 22:56:58.485 ios_chrome_integration_egtests[8613:98963] Attempting to type key /.\n[10:56:58:INFO] 2019-03-19 22:56:58.486 ios_chrome_integration_egtests[8613:98963] Tapping on key: /.\n[10:56:58:INFO] 2019-03-19 22:56:58.554 ios_chrome_integration_egtests[8613:98963] Attempting to type key t.\n[10:56:58:INFO] 2019-03-19 22:56:58.863 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, letters.\n[10:56:58:INFO] 2019-03-19 22:56:58.914 ios_chrome_integration_egtests[8613:98963] Tapping on key: t.\n[10:56:58:INFO] 2019-03-19 22:56:58.983 ios_chrome_integration_egtests[8613:98963] Attempting to type key e.\n[10:56:58:INFO] 2019-03-19 22:56:58.984 ios_chrome_integration_egtests[8613:98963] Tapping on key: e.\n[10:56:59:INFO] 2019-03-19 22:56:59.055 ios_chrome_integration_egtests[8613:98963] Attempting to type key s.\n[10:56:59:INFO] 2019-03-19 22:56:59.056 ios_chrome_integration_egtests[8613:98963] Tapping on key: s.\n[10:56:59:INFO] 2019-03-19 22:56:59.126 ios_chrome_integration_egtests[8613:98963] Attempting to type key t.\n[10:56:59:INFO] 2019-03-19 22:56:59.126 ios_chrome_integration_egtests[8613:98963] Tapping on key: t.\n[10:56:59:INFO] 2019-03-19 22:56:59.196 ios_chrome_integration_egtests[8613:98963] Attempting to type key -.\n[10:56:59:INFO] 2019-03-19 22:56:59.300 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, numbers.\n[10:56:59:INFO] 2019-03-19 22:56:59.371 ios_chrome_integration_egtests[8613:98963] Tapping on key: -.\n[10:56:59:INFO] 2019-03-19 22:56:59.447 ios_chrome_integration_egtests[8613:98963] Attempting to type key p.\n[10:56:59:INFO] 2019-03-19 22:56:59.755 ios_chrome_integration_egtests[8613:98963] Tapping on key: more, letters.\n[10:56:59:INFO] 2019-03-19 22:56:59.807 ios_chrome_integration_egtests[8613:98963] Tapping on key: p.\n[10:56:59:INFO] 2019-03-19 22:56:59.873 ios_chrome_integration_egtests[8613:98963] Attempting to type key a.\n[10:56:59:INFO] 2019-03-19 22:56:59.873 ios_chrome_integration_egtests[8613:98963] Tapping on key: a.\n[10:56:59:INFO] 2019-03-19 22:56:59.938 ios_chrome_integration_egtests[8613:98963] Attempting to type key g.\n[10:56:59:INFO] 2019-03-19 22:56:59.939 ios_chrome_integration_egtests[8613:98963] Tapping on key: g.\n[10:57:04:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (25.718 seconds).\n[10:57:04:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-19 22:57:04.282.\n[10:57:04:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 25.718 (25.726) seconds\n[10:57:04:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-19 22:57:04.283\n[10:57:04:INFO] [0319/225704.284510:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:57:04:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[10:57:07:INFO] [0319/225707.849576:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:57:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (18.353 seconds).\n[10:57:22:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[10:57:52:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (29.825 seconds).\n[10:57:52:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[10:58:09:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.360 seconds).\n[10:58:09:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[10:58:31:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (21.838 seconds).\n[10:58:31:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[10:58:46:INFO] [0319/225846.405239:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:46:INFO] [0319/225846.426717:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:46:INFO] [0319/225846.429327:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:46:INFO] [0319/225846.438327:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:49:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (18.018 seconds).\n[10:58:49:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[10:58:58:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (8.369 seconds).\n[10:58:58:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[10:59:05:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.099 seconds).\n[10:59:05:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[10:59:15:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (9.911 seconds).\n[10:59:15:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[10:59:32:INFO] [0319/225932.986673:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:47:INFO] [0319/225947.161022:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:47:INFO] [0319/225947.184051:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:47:INFO] [0319/225947.187480:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:47:INFO] [0319/225947.212916:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:50:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (35.286 seconds).\n[10:59:50:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[11:00:04:INFO] [0319/230004.223764:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:04:INFO] [0319/230004.232285:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:04:INFO] [0319/230004.232888:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:04:INFO] [0319/230004.237020:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:04:INFO] [0319/230004.240630:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:04:INFO] [0319/230004.247881:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:04:INFO] [0319/230004.255677:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[11:00:07:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (17.057 seconds).\n[11:00:07:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[11:00:22:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.002 seconds).\n[11:00:22:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-19 23:00:22.414.\n[11:00:22:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 198.119 (198.131) seconds\n[11:00:22:INFO] Test Suite 'UKMTestCase' started at 2019-03-19 23:00:22.415\n[11:00:22:INFO] [0319/230022.416656:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:00:22:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[11:00:29:INFO] 2019-03-19 23:00:29.643 ios_chrome_integration_egtests[8613:98963] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7f9fbe2accd0; baseClass = UIButton; frame = (16 615; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x600001e2df80>>, Touch Target: {86, 36}\n[11:00:31:INFO] [0319/230031.064375:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:00:31:INFO] [0319/230031.076870:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:00:31:INFO] [0319/230031.077026:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:00:31:INFO] [0319/230031.177228:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:00:32:INFO] [0319/230032.090476:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:00:43:INFO] [0319/230043.524724:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:00:54:INFO] [0319/230054.063879:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:00:54:INFO] [0319/230054.063997:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:00:55:INFO] [0319/230055.121074:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:00:55:INFO] [0319/230055.194545:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[11:01:06:INFO] [0319/230106.623235:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:06:INFO] [0319/230106.815288:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:01:06:INFO] [0319/230106.815432:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:01:10:INFO] [0319/230110.484599:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:01:12:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (49.969 seconds).\n[11:01:12:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[11:01:21:INFO] [0319/230121.098062:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:21:INFO] [0319/230121.099678:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:21:INFO] [0319/230121.112718:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:01:21:INFO] [0319/230121.112843:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:01:22:INFO] [0319/230122.179583:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:01:39:INFO] [0319/230139.020620:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:39:INFO] [0319/230139.196040:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:01:39:INFO] [0319/230139.196181:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:01:42:INFO] [0319/230142.885151:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:01:44:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (32.383 seconds).\n[11:01:44:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[11:01:53:INFO] [0319/230153.534370:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:53:INFO] [0319/230153.535886:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:53:INFO] [0319/230153.546085:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:01:53:INFO] [0319/230153.546232:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:01:54:INFO] [0319/230154.614533:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:02:06:INFO] [0319/230206.011270:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:02:06:INFO] [0319/230206.197829:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:02:06:INFO] [0319/230206.198001:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:02:09:INFO] [0319/230209.882209:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:02:11:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (26.994 seconds).\n[11:02:11:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[11:02:20:INFO] [0319/230220.488540:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:20:INFO] [0319/230220.490050:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:20:INFO] [0319/230220.502304:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:02:20:INFO] [0319/230220.502435:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:02:21:INFO] [0319/230221.558738:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:02:42:INFO] [0319/230242.539795:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:02:42:INFO] [0319/230242.733856:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:02:42:INFO] [0319/230242.734008:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:02:46:INFO] [0319/230246.432853:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:02:48:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (36.610 seconds).\n[11:02:48:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[11:02:57:INFO] [0319/230257.107248:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:57:INFO] [0319/230257.108855:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:57:INFO] [0319/230257.130222:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:02:57:INFO] [0319/230257.130349:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:02:58:INFO] [0319/230258.152218:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:30:INFO] [0319/230330.365113:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:03:30:INFO] [0319/230330.588434:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:03:30:INFO] [0319/230330.588581:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:03:34:INFO] [0319/230334.262222:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:03:40:INFO] [0319/230340.825652:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:40:INFO] [0319/230340.828133:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:40:INFO] [0319/230340.849686:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:03:40:INFO] [0319/230340.849838:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:03:41:INFO] [0319/230341.879063:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:53:INFO] [0319/230353.477013:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:03:53:INFO] [0319/230353.670578:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:03:53:INFO] [0319/230353.670735:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:03:57:INFO] [0319/230357.432954:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:03:59:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (70.958 seconds).\n[11:03:59:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[11:04:08:INFO] [0319/230408.324799:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:08:INFO] [0319/230408.327314:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:08:INFO] [0319/230408.348088:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:04:08:INFO] [0319/230408.348253:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:04:09:INFO] [0319/230409.372143:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:23:INFO] [0319/230423.358044:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:24:INFO] [0319/230424.873677:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:26:INFO] [0319/230426.336229:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:27:INFO] [0319/230427.817816:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:37:INFO] [0319/230437.825078:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:04:38:INFO] [0319/230438.046846:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:04:38:INFO] [0319/230438.047050:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:04:41:INFO] [0319/230441.745810:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:04:43:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (44.322 seconds).\n[11:04:43:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[11:04:52:INFO] [0319/230452.610600:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:52:INFO] [0319/230452.613073:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:52:INFO] [0319/230452.619059:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:04:52:INFO] [0319/230452.619217:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:04:53:INFO] [0319/230453.662925:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:05:04:INFO] [0319/230504.964701:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:05:05:INFO] [0319/230505.159825:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:05:05:INFO] [0319/230505.159962:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:05:15:INFO] [0319/230515.375479:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:05:15:INFO] [0319/230515.375587:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:05:16:INFO] [0319/230516.408806:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:05:16:INFO] [0319/230516.483702:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[11:05:27:INFO] [0319/230527.748307:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:05:27:INFO] [0319/230527.946519:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with\n[11:05:27:INFO] [0319/230527.946662:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers.\n[11:05:31:INFO] [0319/230531.609327:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:05:33:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (49.833 seconds).\n[11:05:33:INFO] Test Suite 'UKMTestCase' passed at 2019-03-19 23:05:33.494.\n[11:05:33:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 311.070 (311.079) seconds\n[11:05:33:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-19 23:05:33.495.\n[11:05:33:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 820.505 (822.537) seconds\n[11:05:33:INFO] Test Suite 'All tests' passed at 2019-03-19 23:05:33.496.\n[11:05:33:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 820.505 (822.539) seconds\n[11:05:33:INFO] \n[11:05:33:INFO] \n[11:05:33:INFO] Test session log:\n[11:05:33:INFO] \t/b/s/w/ioeWbzUw/12.1 iPhone 7/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-BCB26B7C-4007-4E9A-86BC-FF7E28236461/ios_chrome_integration_egtests_module-D52BADBB-F19F-424E-A7F0-EFCBFBBD0926/Session-ios_chrome_integration_egtests_module-2019-03-19_225118-fZ7Rnc.log\n[11:05:33:INFO] \n[11:05:33:INFO] 2019-03-19 23:05:33.925 xcodebuild[8488:97682] [MT] IDETestOperationsObserverDebug: 855.646 elapsed -- Testing started completed.\n[11:05:33:INFO] 2019-03-19 23:05:33.925 xcodebuild[8488:97682] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[11:05:33:INFO] 2019-03-19 23:05:33.925 xcodebuild[8488:97682] [MT] IDETestOperationsObserverDebug: 855.646 sec, +855.646 sec -- end\n[11:05:33:INFO] ** TEST EXECUTE SUCCEEDED **\n[11:05:33:INFO] \n[11:05:40:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioeWbzUw/run_1553061073.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone 7', '-resultBundlePath', '/b/s/w/ioeWbzUw/12.1 iPhone 7/attempt_0'] finished with 0\n[11:05:40:DEBUG] Test ended.\n[11:05:40:DEBUG] Extracting test data.\n[11:05:40:DEBUG] Retrieving crash reports.\n[11:05:40:DEBUG] Retrieving derived data.\n[11:05:40:DEBUG] Making desktop screenshots.\n[11:05:40:DEBUG] Killing simulators.\n[11:05:45:DEBUG] Wiping simulator.\nDevice wiped.\n[11:05:47:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "13e81acb986c441eda4d59d301fdc95e29e44d80", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43b2784b080b9211", "server_versions": [ "4211-90dafba" ], "started_ts": "2019-03-20T05:50:37.147938", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-sdk-simulator", "buildnumber:6694", "data:e1b4a1a4e63964e9825851ae93225eb6c0ebf6f2", "device_type:iPhone 7", "ios_version:12.1", "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 12.1) on Mac-10.13.6", "swarming.pool.template:none", "swarming.pool.version:6c01c2bd5d548ca0b887eb56d59b3a36edd44394", "test:ios_chrome_integration_egtests", "user:None" ], "task_id": "43b2784b080b9210", "try_number": "1" } ] }