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": [ "4" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-i7-4578U" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "8086", "8086:0a2e" ] }, { "key": "hidpi", "value": [ "0" ] }, { "key": "id", "value": [ "build566-m4" ] }, { "key": "mac_model", "value": [ "Macmini7,1" ] }, { "key": "machine_type", "value": [ "n1-standard-4" ] }, { "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": "build566-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:05:50.092266", "costs_usd": [ 0.11630362882885086 ], "created_ts": "2019-03-20T05:50:12.211829", "duration": 895.5098440647125, "modified_ts": "2019-03-20T06:05:50.092266", "name": "ios_chrome_integration_egtests (iPhone X iOS 12.1)/Mac-10.13.6/126c985fb6/ios12-sdk-simulator/6694", "output": "[I 2019-03-19 22:50:27] About to install Xcode 10b61 in Xcode.app for ios\n[10:50:28:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer\n[10:50:34:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioXCZfrm/run_1553061034.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioXCZfrm/12.1 iPhone X/attempt_0]\n[10:50:35:INFO] User defaults from command line:\nErasing all simulators.\nLaunching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioXCZfrm/run_1553061034.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioXCZfrm/12.1 iPhone X/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/it3LmgVn', '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': 'build566-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lda6uoFc73/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/it3LmgVn/luci_ctx.Qzhj5N.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FX1po8tGkP/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43b277eb06cac611', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/it3LmgVn', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'}\n[10:50:35:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioXCZfrm/12.1 iPhone X/attempt_0\n[10:50:35:INFO] IDETestRunSpecificationPath = /b/s/w/ioXCZfrm/run_1553061034.xctestrun\n[10:50:35:INFO] \n[10:50:35:INFO] Testing started on 'iPhone X'\n[10:50:35:INFO] 2019-03-19 22:50:35.633 xcodebuild[5427:75322] IDETestOperationsObserverDebug: Writing diagnostic log for test session to:\n[10:50:35:INFO] /b/s/w/ioXCZfrm/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-4F0385DE-D63C-4A14-A92B-FE4C915F939A/ios_chrome_integration_egtests_module-14B1F019-FDC1-434D-9C86-B389E820E535/Session-ios_chrome_integration_egtests_module-2019-03-19_225035-zXBZel.log\n[10:50:35:INFO] 2019-03-19 22:50:35.633 xcodebuild[5427:75264] [MT] IDETestOperationsObserverDebug: (71BEA23A-4080-463F-8934-E68A4878CB47) Beginning test session ios_chrome_integration_egtests_module-71BEA23A-4080-463F-8934-E68A4878CB47 at 2019-03-19 22:50:35.633 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7f910e7aa070> {\n[10:50:35:INFO] \t\tSimDevice: iPhone X (7786D687-5027-4D54-84DE-72FD3C5791CE, iOS 12.1, Shutdown)\n[10:50:35:INFO] } (12.1 (16B91))\n[10:50:55:INFO] 2019-03-19 22:50:55.395 ios_chrome_integration_egtests[5547:76427] Crash handler setup started.\n[10:50:55:INFO] 2019-03-19 22:50:55.395 ios_chrome_integration_egtests[5547:76427] Crash handler setup completed.\n[10:50:55:INFO] 2019-03-19 22:50:55.395 ios_chrome_integration_egtests[5547:76427] Enabling accessibility for automation on Simulator.\n[10:50:58:INFO] 2019-03-19 22:50:58.294 ios_chrome_integration_egtests[5547:76427] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fe1508a4800>.\n[10:50:59:INFO] [0319/225059.757043:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/7786D687-5027-4D54-84DE-72FD3C5791CE/data/Containers/Data/Application/186EDC07-2015-47DD-8D21-E6195C02AA17/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/7786D687-5027-4D54-84DE-72FD3C5791CE/data/Containers/Data/Application/186EDC07-2015-47DD-8D21-E6195C02AA17/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false)\n[10:51:05:INFO] Test Suite 'All tests' started at 2019-03-19 22:51:05.392\n[10:51:05:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-19 22:51:05.393\n[10:51:05:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-19 22:51:05.393\n[10:51:05:INFO] [0319/225105.401107:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:51:07:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started.\n[10:51:11:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (4.547 seconds).\n[10:51:11:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-19 22:51:11.845.\n[10:51:11:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 4.547 (6.451) seconds\n[10:51:11:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-19 22:51:11.846\n[10:51:11:INFO] [0319/225111.848658:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:51:11:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started.\n[10:51:14:INFO] [0319/225114.220198:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:51:20:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (9.068 seconds).\n[10:51:20:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started.\n[10:51:23:INFO] [0319/225123.134184:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:51:29:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (8.917 seconds).\n[10:51:29:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started.\n[10:51:32:INFO] [0319/225132.237484:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:51:40:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (10.383 seconds).\n[10:51:40:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started.\n[10:51:42:INFO] [0319/225142.435955:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:51:49:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (8.819 seconds).\n[10:51:49:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started.\n[10:51:50:INFO] [0319/225150.954444:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:51:58:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (9.659 seconds).\n[10:51:58:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started.\n[10:52:00:INFO] [0319/225200.683316:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:04:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (6.183 seconds).\n[10:52:04:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started.\n[10:52:06:INFO] [0319/225206.864043:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:16:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (11.512 seconds).\n[10:52:16:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started.\n[10:52:18:INFO] [0319/225218.376834:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:24:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (8.509 seconds).\n[10:52:24:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started.\n[10:52:26:INFO] [0319/225226.875149:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:34:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (9.803 seconds).\n[10:52:34:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-19 22:52:34.729.\n[10:52:34:INFO] \t Executed 9 tests, with 0 failures (0 unexpected) in 82.852 (82.883) seconds\n[10:52:34:INFO] Test Suite 'CookiesTestCase' started at 2019-03-19 22:52:34.730\n[10:52:34:INFO] [0319/225234.731335:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:52:34:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started.\n[10:52:36:INFO] [0319/225236.716059:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:52:52:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.481 seconds).\n[10:52:52:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started.\n[10:53:07:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.904 seconds).\n[10:53:07:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started.\n[10:53:15:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (8.750 seconds).\n[10:53:15:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started.\n[10:53:29:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.514 seconds).\n[10:53:29:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started.\n[10:53:44:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (14.620 seconds).\n[10:53:44:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-19 22:53:44.008.\n[10:53:44:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 69.270 (69.278) seconds\n[10:53:44:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-19 22:53:44.009\n[10:53:44:INFO] [0319/225344.009890:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:53:44:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started.\n[10:53:45:INFO] 2019-03-19 22:53:45.630 ios_chrome_integration_egtests[5547:76427] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[10:53:47:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.503 seconds).\n[10:53:47:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started.\n[10:53:49:INFO] 2019-03-19 22:53:49.300 ios_chrome_integration_egtests[5547:76427] -- Earl Grey Test Disabled -- Fails on iOS 11.0.\n[10:53:51:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (3.663 seconds).\n[10:53:51:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-19 22:53:51.182.\n[10:53:51:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 7.166 (7.174) seconds\n[10:53:51:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-19 22:53:51.183\n[10:53:51:INFO] [0319/225351.184482:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:53:51:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started.\n[10:54:01:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (9.903 seconds).\n[10:54:01:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started.\n[10:54:11:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (10.164 seconds).\n[10:54:11:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started.\n[10:54:17:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (5.975 seconds).\n[10:54:17:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started.\n[10:54:20:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (3.713 seconds).\n[10:54:20:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started.\n[10:54:30:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (9.862 seconds).\n[10:54:30:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-19 22:54:30.808.\n[10:54:30:INFO] \t Executed 5 tests, with 0 failures (0 unexpected) in 39.616 (39.625) seconds\n[10:54:30:INFO] Test Suite 'FormInputTestCase' started at 2019-03-19 22:54:30.809\n[10:54:30:INFO] [0319/225430.810249:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:54:30:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started.\n[10:54:34:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (3.738 seconds).\n[10:54:34:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started.\n[10:54:36:INFO] [0319/225436.489102:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:54:38:INFO] 2019-03-19 22:54:38.241 ios_chrome_integration_egtests[5547:76427] API error: <_UIKBCompatInputView: 0x7fe152840fb0; frame = (0 0; 0 0); layer = <CALayer: 0x600001d6f300>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[10:54:38:INFO] 2019-03-19 22:54:38.241 ios_chrome_integration_egtests[5547:76427] API error: <_UIKBCompatInputView: 0x7fe152840fb0; frame = (0 0; 0 0); layer = <CALayer: 0x600001d6f300>> returned 0 width, assuming UIViewNoIntrinsicMetric\n[10:54:43:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (9.145 seconds).\n[10:54:43:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-19 22:54:43.697.\n[10:54:43:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 12.883 (12.888) seconds\n[10:54:43:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-19 22:54:43.697\n[10:54:43:INFO] [0319/225443.698642:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:54:43:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started.\n[10:54:45:INFO] [0319/225445.683055:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:54:55:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (11.388 seconds).\n[10:54:55:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started.\n[10:54:58:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (3.730 seconds).\n[10:54:58:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started.\n[10:55:09:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.780 seconds).\n[10:55:09:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started.\n[10:55:14:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (4.775 seconds).\n[10:55:14:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started.\n[10:55:22:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (7.863 seconds).\n[10:55:22:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started.\n[10:55:28:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (6.293 seconds).\n[10:55:28:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-19 22:55:28.534.\n[10:55:28:INFO] \t Executed 6 tests, with 0 failures (0 unexpected) in 44.829 (44.836) seconds\n[10:55:28:INFO] Test Suite 'NTPTilesTest' started at 2019-03-19 22:55:28.535\n[10:55:28:INFO] [0319/225528.535961:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:55:28:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started.\n[10:55:32:INFO] [0319/225532.575726:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:55:38:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (9.997 seconds).\n[10:55:38:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started.\n[10:56:03:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (24.861 seconds).\n[10:56:03:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-19 22:56:03.402.\n[10:56:03:INFO] \t Executed 2 tests, with 0 failures (0 unexpected) in 34.858 (34.867) seconds\n[10:56:03:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-19 22:56:03.402\n[10:56:03:INFO] [0319/225603.403783:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:56:03:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started.\n[10:56:05:INFO] [0319/225605.502347:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:56:12:INFO] 2019-03-19 22:56:12.677 ios_chrome_integration_egtests[5547:76427] Attempting to type key 1.\n[10:56:12:INFO] 2019-03-19 22:56:12.779 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, numbers.\n[10:56:12:INFO] 2019-03-19 22:56:12.865 ios_chrome_integration_egtests[5547:76427] Tapping on key: 1.\n[10:56:12:INFO] 2019-03-19 22:56:12.971 ios_chrome_integration_egtests[5547:76427] Attempting to type key 2.\n[10:56:12:INFO] 2019-03-19 22:56:12.971 ios_chrome_integration_egtests[5547:76427] Tapping on key: 2.\n[10:56:13:INFO] 2019-03-19 22:56:13.047 ios_chrome_integration_egtests[5547:76427] Attempting to type key 7.\n[10:56:13:INFO] 2019-03-19 22:56:13.048 ios_chrome_integration_egtests[5547:76427] Tapping on key: 7.\n[10:56:13:INFO] 2019-03-19 22:56:13.114 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:13:INFO] 2019-03-19 22:56:13.117 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:13:INFO] 2019-03-19 22:56:13.193 ios_chrome_integration_egtests[5547:76427] Attempting to type key 0.\n[10:56:13:INFO] 2019-03-19 22:56:13.194 ios_chrome_integration_egtests[5547:76427] Tapping on key: 0.\n[10:56:13:INFO] 2019-03-19 22:56:13.258 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:13:INFO] 2019-03-19 22:56:13.260 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:13:INFO] 2019-03-19 22:56:13.325 ios_chrome_integration_egtests[5547:76427] Attempting to type key 0.\n[10:56:13:INFO] 2019-03-19 22:56:13.327 ios_chrome_integration_egtests[5547:76427] Tapping on key: 0.\n[10:56:13:INFO] 2019-03-19 22:56:13.392 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:13:INFO] 2019-03-19 22:56:13.393 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:13:INFO] 2019-03-19 22:56:13.458 ios_chrome_integration_egtests[5547:76427] Attempting to type key 1.\n[10:56:13:INFO] 2019-03-19 22:56:13.458 ios_chrome_integration_egtests[5547:76427] Tapping on key: 1.\n[10:56:13:INFO] [0319/225613.505752:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 9\n[10:56:13:INFO] 2019-03-19 22:56:13.533 ios_chrome_integration_egtests[5547:76427] Attempting to type key :.\n[10:56:13:INFO] 2019-03-19 22:56:13.534 ios_chrome_integration_egtests[5547:76427] Tapping on key: :.\n[10:56:13:INFO] 2019-03-19 22:56:13.609 ios_chrome_integration_egtests[5547:76427] Attempting to type key 5.\n[10:56:13:INFO] 2019-03-19 22:56:13.609 ios_chrome_integration_egtests[5547:76427] Tapping on key: 5.\n[10:56:13:INFO] 2019-03-19 22:56:13.679 ios_chrome_integration_egtests[5547:76427] Attempting to type key 5.\n[10:56:13:INFO] 2019-03-19 22:56:13.680 ios_chrome_integration_egtests[5547:76427] Tapping on key: 5.\n[10:56:13:INFO] 2019-03-19 22:56:13.755 ios_chrome_integration_egtests[5547:76427] Attempting to type key 3.\n[10:56:13:INFO] 2019-03-19 22:56:13.756 ios_chrome_integration_egtests[5547:76427] Tapping on key: 3.\n[10:56:13:INFO] 2019-03-19 22:56:13.826 ios_chrome_integration_egtests[5547:76427] Attempting to type key 5.\n[10:56:13:INFO] 2019-03-19 22:56:13.827 ios_chrome_integration_egtests[5547:76427] Tapping on key: 5.\n[10:56:13:INFO] 2019-03-19 22:56:13.897 ios_chrome_integration_egtests[5547:76427] Attempting to type key 4.\n[10:56:13:INFO] 2019-03-19 22:56:13.897 ios_chrome_integration_egtests[5547:76427] Tapping on key: 4.\n[10:56:13:INFO] 2019-03-19 22:56:13.964 ios_chrome_integration_egtests[5547:76427] Attempting to type key /.\n[10:56:13:INFO] 2019-03-19 22:56:13.965 ios_chrome_integration_egtests[5547:76427] Tapping on key: /.\n[10:56:14:INFO] 2019-03-19 22:56:14.027 ios_chrome_integration_egtests[5547:76427] Attempting to type key t.\n[10:56:14:INFO] 2019-03-19 22:56:14.331 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, letters.\n[10:56:14:INFO] 2019-03-19 22:56:14.383 ios_chrome_integration_egtests[5547:76427] Tapping on key: t.\n[10:56:14:INFO] 2019-03-19 22:56:14.439 ios_chrome_integration_egtests[5547:76427] Attempting to type key e.\n[10:56:14:INFO] 2019-03-19 22:56:14.440 ios_chrome_integration_egtests[5547:76427] Tapping on key: e.\n[10:56:14:INFO] 2019-03-19 22:56:14.503 ios_chrome_integration_egtests[5547:76427] Attempting to type key s.\n[10:56:14:INFO] 2019-03-19 22:56:14.504 ios_chrome_integration_egtests[5547:76427] Tapping on key: s.\n[10:56:14:INFO] 2019-03-19 22:56:14.566 ios_chrome_integration_egtests[5547:76427] Attempting to type key t.\n[10:56:14:INFO] 2019-03-19 22:56:14.566 ios_chrome_integration_egtests[5547:76427] Tapping on key: t.\n[10:56:14:INFO] 2019-03-19 22:56:14.628 ios_chrome_integration_egtests[5547:76427] Attempting to type key -.\n[10:56:14:INFO] 2019-03-19 22:56:14.732 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, numbers.\n[10:56:14:INFO] 2019-03-19 22:56:14.783 ios_chrome_integration_egtests[5547:76427] Tapping on key: -.\n[10:56:14:INFO] 2019-03-19 22:56:14.847 ios_chrome_integration_egtests[5547:76427] Attempting to type key p.\n[10:56:15:INFO] 2019-03-19 22:56:15.154 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, letters.\n[10:56:15:INFO] 2019-03-19 22:56:15.207 ios_chrome_integration_egtests[5547:76427] Tapping on key: p.\n[10:56:15:INFO] 2019-03-19 22:56:15.265 ios_chrome_integration_egtests[5547:76427] Attempting to type key a.\n[10:56:15:INFO] 2019-03-19 22:56:15.266 ios_chrome_integration_egtests[5547:76427] Tapping on key: a.\n[10:56:15:INFO] 2019-03-19 22:56:15.326 ios_chrome_integration_egtests[5547:76427] Attempting to type key g.\n[10:56:15:INFO] 2019-03-19 22:56:15.327 ios_chrome_integration_egtests[5547:76427] Tapping on key: g.\n[10:56:15:INFO] 2019-03-19 22:56:15.389 ios_chrome_integration_egtests[5547:76427] Attempting to type key e.\n[10:56:15:INFO] 2019-03-19 22:56:15.390 ios_chrome_integration_egtests[5547:76427] Tapping on key: e.\n[10:56:15:INFO] 2019-03-19 22:56:15.451 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:15:INFO] 2019-03-19 22:56:15.453 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:15:INFO] 2019-03-19 22:56:15.515 ios_chrome_integration_egtests[5547:76427] Attempting to type key h.\n[10:56:15:INFO] 2019-03-19 22:56:15.516 ios_chrome_integration_egtests[5547:76427] Tapping on key: h.\n[10:56:15:INFO] 2019-03-19 22:56:15.593 ios_chrome_integration_egtests[5547:76427] Attempting to type key t.\n[10:56:15:INFO] 2019-03-19 22:56:15.595 ios_chrome_integration_egtests[5547:76427] Tapping on key: t.\n[10:56:15:INFO] 2019-03-19 22:56:15.652 ios_chrome_integration_egtests[5547:76427] Attempting to type key m.\n[10:56:15:INFO] 2019-03-19 22:56:15.653 ios_chrome_integration_egtests[5547:76427] Tapping on key: m.\n[10:56:15:INFO] 2019-03-19 22:56:15.716 ios_chrome_integration_egtests[5547:76427] Attempting to type key l.\n[10:56:15:INFO] 2019-03-19 22:56:15.717 ios_chrome_integration_egtests[5547:76427] Tapping on key: l.\n[10:56:15:INFO] 2019-03-19 22:56:15.776 ios_chrome_integration_egtests[5547:76427] Attempting to type key\n[10:56:15:INFO] .\n[10:56:15:INFO] 2019-03-19 22:56:15.778 ios_chrome_integration_egtests[5547:76427] Tapping on key: Go.\n[10:56:22:INFO] 2019-03-19 22:56:22.109 ios_chrome_integration_egtests[5547:76427] Attempting to type key 1.\n[10:56:22:INFO] 2019-03-19 22:56:22.212 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, numbers.\n[10:56:22:INFO] 2019-03-19 22:56:22.265 ios_chrome_integration_egtests[5547:76427] Tapping on key: 1.\n[10:56:22:INFO] 2019-03-19 22:56:22.346 ios_chrome_integration_egtests[5547:76427] Attempting to type key 2.\n[10:56:22:INFO] 2019-03-19 22:56:22.347 ios_chrome_integration_egtests[5547:76427] Tapping on key: 2.\n[10:56:22:INFO] 2019-03-19 22:56:22.465 ios_chrome_integration_egtests[5547:76427] Attempting to type key 7.\n[10:56:22:INFO] 2019-03-19 22:56:22.466 ios_chrome_integration_egtests[5547:76427] Tapping on key: 7.\n[10:56:22:INFO] 2019-03-19 22:56:22.568 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:22:INFO] 2019-03-19 22:56:22.572 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:22:INFO] 2019-03-19 22:56:22.695 ios_chrome_integration_egtests[5547:76427] Attempting to type key 0.\n[10:56:22:INFO] 2019-03-19 22:56:22.697 ios_chrome_integration_egtests[5547:76427] Tapping on key: 0.\n[10:56:22:INFO] 2019-03-19 22:56:22.811 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:22:INFO] 2019-03-19 22:56:22.813 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:22:INFO] 2019-03-19 22:56:22.945 ios_chrome_integration_egtests[5547:76427] Attempting to type key 0.\n[10:56:22:INFO] 2019-03-19 22:56:22.949 ios_chrome_integration_egtests[5547:76427] Tapping on key: 0.\n[10:56:23:INFO] 2019-03-19 22:56:23.055 ios_chrome_integration_egtests[5547:76427] Attempting to type key ..\n[10:56:23:INFO] 2019-03-19 22:56:23.057 ios_chrome_integration_egtests[5547:76427] Tapping on key: ..\n[10:56:23:INFO] 2019-03-19 22:56:23.151 ios_chrome_integration_egtests[5547:76427] Attempting to type key 1.\n[10:56:23:INFO] 2019-03-19 22:56:23.151 ios_chrome_integration_egtests[5547:76427] Tapping on key: 1.\n[10:56:23:INFO] 2019-03-19 22:56:23.258 ios_chrome_integration_egtests[5547:76427] Attempting to type key :.\n[10:56:23:INFO] 2019-03-19 22:56:23.259 ios_chrome_integration_egtests[5547:76427] Tapping on key: :.\n[10:56:23:INFO] 2019-03-19 22:56:23.347 ios_chrome_integration_egtests[5547:76427] Attempting to type key 5.\n[10:56:23:INFO] 2019-03-19 22:56:23.347 ios_chrome_integration_egtests[5547:76427] Tapping on key: 5.\n[10:56:23:INFO] [0319/225623.401253:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 31\n[10:56:23:INFO] 2019-03-19 22:56:23.428 ios_chrome_integration_egtests[5547:76427] Attempting to type key 5.\n[10:56:23:INFO] 2019-03-19 22:56:23.429 ios_chrome_integration_egtests[5547:76427] Tapping on key: 5.\n[10:56:23:INFO] 2019-03-19 22:56:23.496 ios_chrome_integration_egtests[5547:76427] Attempting to type key 3.\n[10:56:23:INFO] 2019-03-19 22:56:23.496 ios_chrome_integration_egtests[5547:76427] Tapping on key: 3.\n[10:56:23:INFO] 2019-03-19 22:56:23.564 ios_chrome_integration_egtests[5547:76427] Attempting to type key 5.\n[10:56:23:INFO] 2019-03-19 22:56:23.564 ios_chrome_integration_egtests[5547:76427] Tapping on key: 5.\n[10:56:23:INFO] 2019-03-19 22:56:23.630 ios_chrome_integration_egtests[5547:76427] Attempting to type key 4.\n[10:56:23:INFO] 2019-03-19 22:56:23.631 ios_chrome_integration_egtests[5547:76427] Tapping on key: 4.\n[10:56:23:INFO] 2019-03-19 22:56:23.696 ios_chrome_integration_egtests[5547:76427] Attempting to type key /.\n[10:56:23:INFO] 2019-03-19 22:56:23.697 ios_chrome_integration_egtests[5547:76427] Tapping on key: /.\n[10:56:23:INFO] 2019-03-19 22:56:23.760 ios_chrome_integration_egtests[5547:76427] Attempting to type key t.\n[10:56:24:INFO] 2019-03-19 22:56:24.067 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, letters.\n[10:56:24:INFO] 2019-03-19 22:56:24.121 ios_chrome_integration_egtests[5547:76427] Tapping on key: t.\n[10:56:24:INFO] 2019-03-19 22:56:24.180 ios_chrome_integration_egtests[5547:76427] Attempting to type key e.\n[10:56:24:INFO] 2019-03-19 22:56:24.180 ios_chrome_integration_egtests[5547:76427] Tapping on key: e.\n[10:56:24:INFO] 2019-03-19 22:56:24.244 ios_chrome_integration_egtests[5547:76427] Attempting to type key s.\n[10:56:24:INFO] 2019-03-19 22:56:24.245 ios_chrome_integration_egtests[5547:76427] Tapping on key: s.\n[10:56:24:INFO] 2019-03-19 22:56:24.309 ios_chrome_integration_egtests[5547:76427] Attempting to type key t.\n[10:56:24:INFO] 2019-03-19 22:56:24.309 ios_chrome_integration_egtests[5547:76427] Tapping on key: t.\n[10:56:24:INFO] 2019-03-19 22:56:24.372 ios_chrome_integration_egtests[5547:76427] Attempting to type key -.\n[10:56:24:INFO] 2019-03-19 22:56:24.473 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, numbers.\n[10:56:24:INFO] 2019-03-19 22:56:24.525 ios_chrome_integration_egtests[5547:76427] Tapping on key: -.\n[10:56:24:INFO] 2019-03-19 22:56:24.590 ios_chrome_integration_egtests[5547:76427] Attempting to type key p.\n[10:56:24:INFO] 2019-03-19 22:56:24.896 ios_chrome_integration_egtests[5547:76427] Tapping on key: more, letters.\n[10:56:24:INFO] 2019-03-19 22:56:24.950 ios_chrome_integration_egtests[5547:76427] Tapping on key: p.\n[10:56:25:INFO] 2019-03-19 22:56:25.010 ios_chrome_integration_egtests[5547:76427] Attempting to type key a.\n[10:56:25:INFO] 2019-03-19 22:56:25.011 ios_chrome_integration_egtests[5547:76427] Tapping on key: a.\n[10:56:25:INFO] 2019-03-19 22:56:25.075 ios_chrome_integration_egtests[5547:76427] Attempting to type key g.\n[10:56:25:INFO] 2019-03-19 22:56:25.076 ios_chrome_integration_egtests[5547:76427] Tapping on key: g.\n[10:56:29:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (26.416 seconds).\n[10:56:29:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-19 22:56:29.831.\n[10:56:29:INFO] \t Executed 1 test, with 0 failures (0 unexpected) in 26.416 (26.429) seconds\n[10:56:29:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-19 22:56:29.832\n[10:56:29:INFO] [0319/225629.832873:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:56:29:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started.\n[10:56:33:INFO] [0319/225633.691433:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico\n[10:56:46:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (16.499 seconds).\n[10:56:46:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started.\n[10:57:15:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (29.022 seconds).\n[10:57:15:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started.\n[10:57:33:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (17.742 seconds).\n[10:57:33:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started.\n[10:57:51:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (18.033 seconds).\n[10:57:51:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started.\n[10:58:07:INFO] [0319/225807.652886:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:07:INFO] [0319/225807.683526:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:07:INFO] [0319/225807.687324:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:07:INFO] [0319/225807.688941:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:07:INFO] [0319/225807.700198:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:58:10:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (19.626 seconds).\n[10:58:10:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started.\n[10:58:17:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (6.453 seconds).\n[10:58:17:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started.\n[10:58:24:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (7.544 seconds).\n[10:58:24:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started.\n[10:58:35:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (10.533 seconds).\n[10:58:35:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started.\n[10:58:57:INFO] [0319/225857.903881:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:09:INFO] [0319/225909.636014:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:09:INFO] [0319/225909.666404:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:09:INFO] [0319/225909.669558:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:09:INFO] [0319/225909.670496:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:09:INFO] [0319/225909.682903:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:12:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (37.500 seconds).\n[10:59:12:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started.\n[10:59:28:INFO] [0319/225928.282892:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:28:INFO] [0319/225928.293184:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:28:INFO] [0319/225928.294415:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:28:INFO] [0319/225928.304046:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:28:INFO] [0319/225928.311560:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred\n[10:59:31:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (18.565 seconds).\n[10:59:31:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started.\n[10:59:46:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.399 seconds).\n[10:59:46:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-19 22:59:46.759.\n[10:59:46:INFO] \t Executed 11 tests, with 0 failures (0 unexpected) in 196.917 (196.928) seconds\n[10:59:46:INFO] Test Suite 'UKMTestCase' started at 2019-03-19 22:59:46.760\n[10:59:46:INFO] [0319/225946.761583:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[10:59:46:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started.\n[10:59:54:INFO] 2019-03-19 22:59:54.235 ios_chrome_integration_egtests[5547:76427] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fe152f5ce10; baseClass = UIButton; frame = (16 726; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x60000321b800>>, Touch Target: {86, 36}\n[10:59:55:INFO] [0319/225955.703654:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[10:59:55:INFO] [0319/225955.712916:ERROR:account_tracker.cc(264)] OnOAuthError\n[10:59:55:INFO] [0319/225955.713007:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[10:59:55:INFO] [0319/225955.734647:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[10:59:56:INFO] [0319/225956.774796:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:00:08:INFO] [0319/230008.580558:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:00:19:INFO] [0319/230019.453672:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:00:19:INFO] [0319/230019.454121:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:00:20:INFO] [0319/230020.542868:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:00:20:INFO] [0319/230020.655915:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[11:00:32:INFO] [0319/230032.315202:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:00:36:INFO] [0319/230036.277387:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:00:38:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (51.411 seconds).\n[11:00:38:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started.\n[11:00:47:INFO] [0319/230047.284382:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:00:47:INFO] [0319/230047.286541:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:00:47:INFO] [0319/230047.290790:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:00:47:INFO] [0319/230047.290896:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:00:48:INFO] [0319/230048.393610:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:01:05:INFO] [0319/230105.870266:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:09:INFO] [0319/230109.831562:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:01:11:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (33.547 seconds).\n[11:01:11:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started.\n[11:01:20:INFO] [0319/230120.797255:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:20:INFO] [0319/230120.798638:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:01:20:INFO] [0319/230120.798825:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:01:20:INFO] [0319/230120.800488:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:21:INFO] [0319/230121.900003:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:01:33:INFO] [0319/230133.716960:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:01:37:INFO] [0319/230137.681684:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:01:39:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (27.856 seconds).\n[11:01:39:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started.\n[11:01:48:INFO] [0319/230148.641974:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:48:INFO] [0319/230148.644367:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:01:48:INFO] [0319/230148.644533:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:01:48:INFO] [0319/230148.648776:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:01:49:INFO] [0319/230149.752267:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:02:11:INFO] [0319/230211.717537:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:02:15:INFO] [0319/230215.681130:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:02:17:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (38.037 seconds).\n[11:02:17:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started.\n[11:02:26:INFO] [0319/230226.724818:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:02:26:INFO] [0319/230226.725048:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:02:26:INFO] [0319/230226.728409:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:26:INFO] [0319/230226.732161:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:02:27:INFO] [0319/230227.821025:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:00:INFO] [0319/230300.721030:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:03:04:INFO] [0319/230304.691290:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:03:11:INFO] [0319/230311.582709:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:11:INFO] [0319/230311.584877:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:11:INFO] [0319/230311.590142:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:03:11:INFO] [0319/230311.590262:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:03:12:INFO] [0319/230312.690810:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:24:INFO] [0319/230324.504119:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:03:28:INFO] [0319/230328.464360:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:03:30:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (72.743 seconds).\n[11:03:30:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started.\n[11:03:39:INFO] [0319/230339.456302:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:39:INFO] [0319/230339.458320:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:03:39:INFO] [0319/230339.458989:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:03:39:INFO] [0319/230339.459132:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:03:40:INFO] [0319/230340.558626:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:54:INFO] [0319/230354.867061:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:56:INFO] [0319/230356.441838:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:57:INFO] [0319/230357.991679:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:03:59:INFO] [0319/230359.558058:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:09:INFO] [0319/230409.856904:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:04:13:INFO] [0319/230413.830454:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:04:15:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (45.371 seconds).\n[11:04:15:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started.\n[11:04:24:INFO] [0319/230424.836488:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:24:INFO] [0319/230424.838635:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag.\n[11:04:24:INFO] [0319/230424.843558:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:04:24:INFO] [0319/230424.843674:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:04:25:INFO] [0319/230425.949985:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:37:INFO] [0319/230437.766369:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:04:48:INFO] [0319/230448.676249:ERROR:account_tracker.cc(264)] OnOAuthError\n[11:04:48:INFO] [0319/230448.676502:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID\n[11:04:49:INFO] [0319/230449.771480:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now\n[11:04:49:INFO] [0319/230449.882560:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session.\n[11:05:01:INFO] [0319/230501.586420:INFO:signin_manager.cc(125)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1\n[11:05:05:INFO] [0319/230505.548742:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist.\n[11:05:07:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (51.714 seconds).\n[11:05:07:INFO] Test Suite 'UKMTestCase' passed at 2019-03-19 23:05:07.447.\n[11:05:07:INFO] \t Executed 7 tests, with 0 failures (0 unexpected) in 320.679 (320.687) seconds\n[11:05:07:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-19 23:05:07.448.\n[11:05:07:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 840.032 (842.055) seconds\n[11:05:07:INFO] Test Suite 'All tests' passed at 2019-03-19 23:05:07.449.\n[11:05:07:INFO] \t Executed 51 tests, with 0 failures (0 unexpected) in 840.032 (842.057) seconds\n[11:05:07:INFO] \n[11:05:07:INFO] \n[11:05:07:INFO] Test session log:\n[11:05:07:INFO] \t/b/s/w/ioXCZfrm/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-4F0385DE-D63C-4A14-A92B-FE4C915F939A/ios_chrome_integration_egtests_module-14B1F019-FDC1-434D-9C86-B389E820E535/Session-ios_chrome_integration_egtests_module-2019-03-19_225035-zXBZel.log\n[11:05:07:INFO] \n[11:05:07:INFO] 2019-03-19 23:05:07.922 xcodebuild[5427:75264] [MT] IDETestOperationsObserverDebug: 872.362 elapsed -- Testing started completed.\n[11:05:07:INFO] 2019-03-19 23:05:07.922 xcodebuild[5427:75264] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start\n[11:05:07:INFO] 2019-03-19 23:05:07.922 xcodebuild[5427:75264] [MT] IDETestOperationsObserverDebug: 872.362 sec, +872.362 sec -- end\n[11:05:07:INFO] ** TEST EXECUTE SUCCEEDED **\n[11:05:07:INFO] \n[11:05:13:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioXCZfrm/run_1553061034.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioXCZfrm/12.1 iPhone X/attempt_0'] finished with 0\n[11:05:13:DEBUG] Test ended.\n[11:05:13:DEBUG] Extracting test data.\n[11:05:13:DEBUG] Retrieving crash reports.\n[11:05:13:DEBUG] Retrieving derived data.\n[11:05:13:DEBUG] Making desktop screenshots.\n[11:05:14:DEBUG] Killing simulators.\n[11:05:19:DEBUG] Wiping simulator.\nDevice wiped.\n[11:05:21:DEBUG] End of tear_down.\n", "outputs_ref": { "isolated": "bb7c7ae9ecc6b99c9a08071ce94928a739081927", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43b277eb06cac611", "server_versions": [ "4211-90dafba" ], "started_ts": "2019-03-20T05:50:12.521059", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:ios12-sdk-simulator", "buildnumber:6694", "data:126c985fb6b1ea38521fb672dd5ae0f08c9ae6f9", "device_type:iPhone X", "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 X 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": "43b277eb06cac610", "try_number": "1" } ] }