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

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmpxMG5Ac.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/3O12oRl --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpaMPuZzmerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmp829Qjs.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmp11Ezmh.json in dir /b/s/w/ir/k: allow_subannotations: False cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmpxMG5Ac.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/3O12oRl', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpaMPuZzmerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmp829Qjs.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/s/w/ir/tmp/t/tmp11Ezmh.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_chrome_integration_egtests (iPhone X iOS 12.1) on Mac-10.13.6 (2)',) ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.v5EUacQouh/Render BOTO_CONFIG: /b/s/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 53620 DOCKER_CONFIG: /b/s/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /Users/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8918452603915928944 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itztLnjo/luci_context.183341123 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/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 PWD: /b/s/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SSH_AUTH_SOCK: /private/tmp/com.apple.launchd.6DWfjI6czf/Listeners SWARMING_BOT_ID: build111-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43b4a9bf4da31e11 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot VERSIONER_PYTHON_PREFER_32_BIT: no VERSIONER_PYTHON_VERSION: 2.7 VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython XPC_FLAGS: 0x0 XPC_SERVICE_NAME: 0 _: /b/s/w/ir/cipd_bin_packages/vpython __CF_USER_TEXT_ENCODING: 0x1F4:0x0:0x0 Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-03-20 11:43:01,295 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/3O12oRl' 2019-03-20 11:43:01,295 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/3O12oRl' already exists! 2019-03-20 11:43:01,295 - root: [WARNING] task_output_dir existing content: [] 2019-03-20 11:43:01,295 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmp11Ezmh.json -output-dir /b/s/w/ir/k/recipe_cleanup/3O12oRl -task-summary-json /b/s/w/ir/tmp/t/tmp829Qjs.json [D2019-03-20T11:43:01.310530-07:00 1541 0 auth.go:1265] Minting a new token {"key":"luci_ctx/1316096eb1fbca8bf8e69e9fc91bca0bfc0b762856426ab600cb25794fdc2e35", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-20T11:43:01.310623-07:00 1541 0 luci_ctx.go:138] POST http://127.0.0.1:53609/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/1316096eb1fbca8bf8e69e9fc91bca0bfc0b762856426ab600cb25794fdc2e35", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-20T11:43:01.312114-07:00 1541 0 auth.go:1222] Token expires in 57m55.687926s {"key":"luci_ctx/1316096eb1fbca8bf8e69e9fc91bca0bfc0b762856426ab600cb25794fdc2e35", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 43b4b8dbd23eaf10: exit 0 [I 2019-03-20 11:18:27] About to install Xcode 10b61 in Xcode.app for ios [11:18:28:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [11:18:37:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioLWIFwC/run_1553105917.xctestrun -destination platform=iOS Simulator,OS=12.1,name=iPhone X -resultBundlePath /b/s/w/ioLWIFwC/12.1 iPhone X/attempt_0] [11:18:38:INFO] User defaults from command line: Erasing all simulators. Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioLWIFwC/run_1553105917.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioLWIFwC/12.1 iPhone X/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/itP_AZXd', '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': 'vm1136-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQIWpJ6qog/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/itP_AZXd/luci_ctx.NAE_7F.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.l39PSxcffd/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43b4b8dbd23eaf11', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/itP_AZXd', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [11:18:38:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioLWIFwC/12.1 iPhone X/attempt_0 [11:18:38:INFO] IDETestRunSpecificationPath = /b/s/w/ioLWIFwC/run_1553105917.xctestrun [11:18:38:INFO] [11:18:38:INFO] Testing started on 'iPhone X' [11:18:38:INFO] 2019-03-20 11:18:38.634 xcodebuild[37692:1984921] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [11:18:38:INFO] /b/s/w/ioLWIFwC/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-35983AF7-A1FD-42FE-93E3-EE76AB1C8285/ios_chrome_integration_egtests_module-0A85E103-A86F-46AF-8EA6-7E34E9616EA4/Session-ios_chrome_integration_egtests_module-2019-03-20_111838-b3QPvH.log [11:18:38:INFO] 2019-03-20 11:18:38.635 xcodebuild[37692:1984898] [MT] IDETestOperationsObserverDebug: (FDEDBC5E-AB48-450D-8BF2-024DC4B24E5D) Beginning test session ios_chrome_integration_egtests_module-FDEDBC5E-AB48-450D-8BF2-024DC4B24E5D at 2019-03-20 11:18:38.635 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fbd7ec14a40> { [11:18:38:INFO] SimDevice: iPhone X (2662D6F7-4508-4A33-BBD0-40504581E051, iOS 12.1, Shutdown) [11:18:38:INFO] } (12.1 (16B91)) [11:18:55:INFO] 2019-03-20 11:18:55.688 ios_chrome_integration_egtests[37813:1986157] Crash handler setup started. [11:18:55:INFO] 2019-03-20 11:18:55.688 ios_chrome_integration_egtests[37813:1986157] Crash handler setup completed. [11:18:55:INFO] 2019-03-20 11:18:55.688 ios_chrome_integration_egtests[37813:1986157] Enabling accessibility for automation on Simulator. [11:18:57:INFO] 2019-03-20 11:18:57.973 ios_chrome_integration_egtests[37813:1986157] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f88e1035600>. [11:18:59:INFO] [0320/111859.373455:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2662D6F7-4508-4A33-BBD0-40504581E051/data/Containers/Data/Application/B3B6BCA0-D79C-4C14-BB37-76C43936FA48/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/2662D6F7-4508-4A33-BBD0-40504581E051/data/Containers/Data/Application/B3B6BCA0-D79C-4C14-BB37-76C43936FA48/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [11:19:05:INFO] Test Suite 'All tests' started at 2019-03-20 11:19:05.151 [11:19:05:INFO] Test Suite 'ios_chrome_integration_egtests.app' started at 2019-03-20 11:19:05.152 [11:19:05:INFO] Test Suite 'BrowserStateServicesTestCase' started at 2019-03-20 11:19:05.152 [11:19:05:INFO] [0320/111905.159939:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:19:07:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' started. [11:19:11:INFO] Test Case '-[BrowserStateServicesTestCase testConnectionToIdentityService]' passed (4.010 seconds). [11:19:11:INFO] Test Suite 'BrowserStateServicesTestCase' passed at 2019-03-20 11:19:11.037. [11:19:11:INFO] Executed 1 test, with 0 failures (0 unexpected) in 4.010 (5.885) seconds [11:19:11:INFO] Test Suite 'ContextMenuTestCase' started at 2019-03-20 11:19:11.038 [11:19:11:INFO] [0320/111911.039747:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:19:11:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' started. [11:19:13:INFO] [0320/111913.364190:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:19:23:INFO] Test Case '-[ContextMenuTestCase testAppropriateContextMenu]' passed (12.580 seconds). [11:19:23:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' started. [11:19:25:INFO] [0320/111925.796038:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:19:33:INFO] Test Case '-[ContextMenuTestCase testContextMenuCancelSystemTouchesMetric]' passed (9.656 seconds). [11:19:33:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' started. [11:19:35:INFO] [0320/111935.775098:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:19:45:INFO] Test Case '-[ContextMenuTestCase testContextMenuDisplayedOnImage]' passed (12.307 seconds). [11:19:45:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' started. [11:19:47:INFO] [0320/111947.711858:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:19:55:INFO] Test Case '-[ContextMenuTestCase testContextMenuElementFetchDurationMetric]' passed (9.590 seconds). [11:19:55:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' started. [11:19:57:INFO] [0320/111957.429874:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:20:05:INFO] Test Case '-[ContextMenuTestCase testContextMenuOpenInNewTab]' passed (10.745 seconds). [11:20:05:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' started. [11:20:08:INFO] [0320/112008.564525:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:20:12:INFO] Test Case '-[ContextMenuTestCase testContextMenuSelectedTextCallout]' passed (7.056 seconds). [11:20:12:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' started. [11:20:15:INFO] [0320/112015.334226:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:20:27:INFO] Test Case '-[ContextMenuTestCase testDismissContextMenu]' passed (14.800 seconds). [11:20:27:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' started. [11:20:30:INFO] [0320/112030.017207:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:20:37:INFO] Test Case '-[ContextMenuTestCase testOpenImageInCurrentTabFromContextMenu]' passed (9.632 seconds). [11:20:37:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' started. [11:20:39:INFO] [0320/112039.525343:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:20:48:INFO] Test Case '-[ContextMenuTestCase testOpenImageInNewTabFromContextMenu]' passed (11.194 seconds). [11:20:48:INFO] Test Suite 'ContextMenuTestCase' passed at 2019-03-20 11:20:48.612. [11:20:48:INFO] Executed 9 tests, with 0 failures (0 unexpected) in 97.560 (97.574) seconds [11:20:48:INFO] Test Suite 'CookiesTestCase' started at 2019-03-20 11:20:48.613 [11:20:48:INFO] [0320/112048.614318:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:20:48:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' started. [11:20:50:INFO] [0320/112050.815637:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:21:06:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromIncognito]' passed (17.592 seconds). [11:21:06:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' started. [11:21:20:INFO] Test Case '-[CookiesTestCase testClearIncognitoFromMain]' passed (14.639 seconds). [11:21:20:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' started. [11:21:29:INFO] Test Case '-[CookiesTestCase testShareCookiesBetweenTabs]' passed (9.124 seconds). [11:21:29:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' started. [11:21:43:INFO] Test Case '-[CookiesTestCase testSwitchToIncognito]' passed (13.745 seconds). [11:21:43:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' started. [11:21:58:INFO] Test Case '-[CookiesTestCase testSwitchToMain]' passed (15.147 seconds). [11:21:58:INFO] Test Suite 'CookiesTestCase' passed at 2019-03-20 11:21:58.867. [11:21:58:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 70.246 (70.254) seconds [11:21:58:INFO] Test Suite 'CredentialManagerEGTest' started at 2019-03-20 11:21:58.868 [11:21:58:INFO] [0320/112158.869444:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:21:58:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' started. [11:22:00:INFO] 2019-03-20 11:22:00.477 ios_chrome_integration_egtests[37813:1986157] -- Earl Grey Test Disabled -- Fails on iOS 11.0. [11:22:02:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsOnAutoSignIn]' passed (3.516 seconds). [11:22:02:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' started. [11:22:04:INFO] 2019-03-20 11:22:04.544 ios_chrome_integration_egtests[37813:1986157] -- Earl Grey Test Disabled -- Fails on iOS 11.0. [11:22:06:INFO] Test Case '-[CredentialManagerEGTest testNotificationAppearsWhenTabIsActive]' passed (4.053 seconds). [11:22:06:INFO] Test Suite 'CredentialManagerEGTest' passed at 2019-03-20 11:22:06.445. [11:22:06:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 7.569 (7.577) seconds [11:22:06:INFO] Test Suite 'FeatureEngagementTestCase' started at 2019-03-20 11:22:06.446 [11:22:06:INFO] [0320/112206.447176:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:22:06:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' started. [11:22:17:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureReadingListAlreadyUsed]' passed (11.374 seconds). [11:22:17:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' started. [11:22:30:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureShouldShow]' passed (13.116 seconds). [11:22:30:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' started. [11:22:38:INFO] Test Case '-[FeatureEngagementTestCase testBadgedReadingListFeatureTooFewChromeOpens]' passed (7.649 seconds). [11:22:38:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' started. [11:22:42:INFO] Test Case '-[FeatureEngagementTestCase testBottomToolbarDontAppearOnNonSplitToolbar]' passed (4.063 seconds). [11:22:42:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' started. [11:22:54:INFO] Test Case '-[FeatureEngagementTestCase testNewTabTipPromoShouldShow]' passed (11.535 seconds). [11:22:54:INFO] Test Suite 'FeatureEngagementTestCase' passed at 2019-03-20 11:22:54.192. [11:22:54:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 47.737 (47.747) seconds [11:22:54:INFO] Test Suite 'FormInputTestCase' started at 2019-03-20 11:22:54.193 [11:22:54:INFO] [0320/112254.194340:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:22:54:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' started. [11:22:58:INFO] Test Case '-[FormInputTestCase testCloseKeyboardWhenNotVisible]' passed (4.123 seconds). [11:22:58:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' started. [11:23:00:INFO] [0320/112300.544668:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:23:02:INFO] 2019-03-20 11:23:02.753 ios_chrome_integration_egtests[37813:1986157] API error: <_UIKBCompatInputView: 0x7f88e7905920; frame = (0 0; 0 0); layer = <CALayer: 0x6000034f3fe0>> returned 0 width, assuming UIViewNoIntrinsicMetric [11:23:02:INFO] 2019-03-20 11:23:02.754 ios_chrome_integration_egtests[37813:1986157] API error: <_UIKBCompatInputView: 0x7f88e7905920; frame = (0 0; 0 0); layer = <CALayer: 0x6000034f3fe0>> returned 0 width, assuming UIViewNoIntrinsicMetric [11:23:09:INFO] Test Case '-[FormInputTestCase testFindDefaultFormAssistControls]' passed (11.134 seconds). [11:23:09:INFO] Test Suite 'FormInputTestCase' passed at 2019-03-20 11:23:09.457. [11:23:09:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 15.257 (15.264) seconds [11:23:09:INFO] Test Suite 'HandoffManagerTestCase' started at 2019-03-20 11:23:09.457 [11:23:09:INFO] [0320/112309.458883:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:23:09:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' started. [11:23:11:INFO] [0320/112311.659726:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:23:21:INFO] Test Case '-[HandoffManagerTestCase testMultipleSwitchingTabs]' passed (11.833 seconds). [11:23:21:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' started. [11:23:25:INFO] Test Case '-[HandoffManagerTestCase testNewTabPageEmptyURL]' passed (4.120 seconds). [11:23:25:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' started. [11:23:36:INFO] Test Case '-[HandoffManagerTestCase testSwitchBetweenNormalAndIncognitoTabs]' passed (10.756 seconds). [11:23:36:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' started. [11:23:41:INFO] Test Case '-[HandoffManagerTestCase testTypicalURL]' passed (5.243 seconds). [11:23:41:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' started. [11:23:50:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewIncognitoTab]' passed (8.766 seconds). [11:23:50:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' started. [11:23:57:INFO] Test Case '-[HandoffManagerTestCase testTypicalURLInNewTab]' passed (7.208 seconds). [11:23:57:INFO] Test Suite 'HandoffManagerTestCase' passed at 2019-03-20 11:23:57.393. [11:23:57:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 47.927 (47.936) seconds [11:23:57:INFO] Test Suite 'NTPTilesTest' started at 2019-03-20 11:23:57.394 [11:23:57:INFO] [0320/112357.396190:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:23:57:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' started. [11:24:01:INFO] [0320/112401.995291:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:24:08:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterLoadURL]' passed (11.363 seconds). [11:24:08:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' started. [11:24:39:INFO] Test Case '-[NTPTilesTest testTopSitesTileAfterRedirect]' passed (31.134 seconds). [11:24:39:INFO] Test Suite 'NTPTilesTest' passed at 2019-03-20 11:24:39.901. [11:24:39:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 42.497 (42.507) seconds [11:24:39:INFO] Test Suite 'PrerenderTestCase' started at 2019-03-20 11:24:39.902 [11:24:39:INFO] [0320/112439.903766:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:24:39:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' started. [11:24:42:INFO] [0320/112442.392830:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:24:51:INFO] 2019-03-20 11:24:51.367 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 1. [11:24:51:INFO] 2019-03-20 11:24:51.470 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, numbers. [11:24:51:INFO] 2019-03-20 11:24:51.557 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 1. [11:24:51:INFO] 2019-03-20 11:24:51.678 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 2. [11:24:51:INFO] 2019-03-20 11:24:51.679 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 2. [11:24:51:INFO] 2019-03-20 11:24:51.748 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 7. [11:24:51:INFO] 2019-03-20 11:24:51.749 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 7. [11:24:51:INFO] 2019-03-20 11:24:51.816 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:24:51:INFO] 2019-03-20 11:24:51.818 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:24:51:INFO] 2019-03-20 11:24:51.887 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 0. [11:24:51:INFO] 2019-03-20 11:24:51.888 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 0. [11:24:52:INFO] 2019-03-20 11:24:52.109 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:24:52:INFO] 2019-03-20 11:24:52.111 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:24:52:INFO] [0320/112452.156761:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 5 [11:24:52:INFO] 2019-03-20 11:24:52.325 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 0. [11:24:52:INFO] 2019-03-20 11:24:52.326 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 0. [11:24:52:INFO] [0320/112452.370650:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 7 [11:24:52:INFO] 2019-03-20 11:24:52.542 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:24:52:INFO] 2019-03-20 11:24:52.543 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:24:52:INFO] 2019-03-20 11:24:52.712 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 1. [11:24:52:INFO] 2019-03-20 11:24:52.714 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 1. [11:24:52:INFO] [0320/112452.763444:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 11 [11:24:52:INFO] 2019-03-20 11:24:52.785 ios_chrome_integration_egtests[37813:1986157] Attempting to type key :. [11:24:52:INFO] 2019-03-20 11:24:52.786 ios_chrome_integration_egtests[37813:1986157] Tapping on key: :. [11:24:52:INFO] 2019-03-20 11:24:52.970 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 6. [11:24:52:INFO] 2019-03-20 11:24:52.972 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 6. [11:24:53:INFO] [0320/112453.018894:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 15 [11:24:53:INFO] 2019-03-20 11:24:53.044 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 0. [11:24:53:INFO] 2019-03-20 11:24:53.045 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 0. [11:24:53:INFO] 2019-03-20 11:24:53.329 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 5. [11:24:53:INFO] 2019-03-20 11:24:53.330 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 5. [11:24:53:INFO] 2019-03-20 11:24:53.401 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 6. [11:24:53:INFO] 2019-03-20 11:24:53.402 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 6. [11:24:53:INFO] 2019-03-20 11:24:53.542 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 3. [11:24:53:INFO] 2019-03-20 11:24:53.542 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 3. [11:24:53:INFO] 2019-03-20 11:24:53.610 ios_chrome_integration_egtests[37813:1986157] Attempting to type key /. [11:24:53:INFO] 2019-03-20 11:24:53.611 ios_chrome_integration_egtests[37813:1986157] Tapping on key: /. [11:24:53:INFO] 2019-03-20 11:24:53.874 ios_chrome_integration_egtests[37813:1986157] Attempting to type key t. [11:24:54:INFO] 2019-03-20 11:24:54.181 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, letters. [11:24:54:INFO] 2019-03-20 11:24:54.235 ios_chrome_integration_egtests[37813:1986157] Tapping on key: t. [11:24:54:INFO] 2019-03-20 11:24:54.299 ios_chrome_integration_egtests[37813:1986157] Attempting to type key e. [11:24:54:INFO] 2019-03-20 11:24:54.300 ios_chrome_integration_egtests[37813:1986157] Tapping on key: e. [11:24:54:INFO] 2019-03-20 11:24:54.363 ios_chrome_integration_egtests[37813:1986157] Attempting to type key s. [11:24:54:INFO] 2019-03-20 11:24:54.364 ios_chrome_integration_egtests[37813:1986157] Tapping on key: s. [11:24:54:INFO] 2019-03-20 11:24:54.430 ios_chrome_integration_egtests[37813:1986157] Attempting to type key t. [11:24:54:INFO] 2019-03-20 11:24:54.431 ios_chrome_integration_egtests[37813:1986157] Tapping on key: t. [11:24:54:INFO] 2019-03-20 11:24:54.495 ios_chrome_integration_egtests[37813:1986157] Attempting to type key -. [11:24:54:INFO] 2019-03-20 11:24:54.599 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, numbers. [11:24:54:INFO] 2019-03-20 11:24:54.667 ios_chrome_integration_egtests[37813:1986157] Tapping on key: -. [11:24:54:INFO] 2019-03-20 11:24:54.738 ios_chrome_integration_egtests[37813:1986157] Attempting to type key p. [11:24:55:INFO] 2019-03-20 11:24:55.042 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, letters. [11:24:55:INFO] 2019-03-20 11:24:55.094 ios_chrome_integration_egtests[37813:1986157] Tapping on key: p. [11:24:55:INFO] 2019-03-20 11:24:55.158 ios_chrome_integration_egtests[37813:1986157] Attempting to type key a. [11:24:55:INFO] 2019-03-20 11:24:55.159 ios_chrome_integration_egtests[37813:1986157] Tapping on key: a. [11:24:55:INFO] 2019-03-20 11:24:55.224 ios_chrome_integration_egtests[37813:1986157] Attempting to type key g. [11:24:55:INFO] 2019-03-20 11:24:55.225 ios_chrome_integration_egtests[37813:1986157] Tapping on key: g. [11:24:55:INFO] 2019-03-20 11:24:55.289 ios_chrome_integration_egtests[37813:1986157] Attempting to type key e. [11:24:55:INFO] 2019-03-20 11:24:55.290 ios_chrome_integration_egtests[37813:1986157] Tapping on key: e. [11:24:55:INFO] 2019-03-20 11:24:55.409 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:24:55:INFO] 2019-03-20 11:24:55.411 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:24:55:INFO] 2019-03-20 11:24:55.477 ios_chrome_integration_egtests[37813:1986157] Attempting to type key h. [11:24:55:INFO] 2019-03-20 11:24:55.477 ios_chrome_integration_egtests[37813:1986157] Tapping on key: h. [11:24:55:INFO] 2019-03-20 11:24:55.684 ios_chrome_integration_egtests[37813:1986157] Attempting to type key t. [11:24:55:INFO] 2019-03-20 11:24:55.686 ios_chrome_integration_egtests[37813:1986157] Tapping on key: t. [11:24:55:INFO] 2019-03-20 11:24:55.905 ios_chrome_integration_egtests[37813:1986157] Attempting to type key m. [11:24:55:INFO] 2019-03-20 11:24:55.906 ios_chrome_integration_egtests[37813:1986157] Tapping on key: m. [11:24:56:INFO] 2019-03-20 11:24:56.125 ios_chrome_integration_egtests[37813:1986157] Attempting to type key l. [11:24:56:INFO] 2019-03-20 11:24:56.126 ios_chrome_integration_egtests[37813:1986157] Tapping on key: l. [11:24:56:INFO] 2019-03-20 11:24:56.315 ios_chrome_integration_egtests[37813:1986157] Attempting to type key [11:24:56:INFO] . [11:24:56:INFO] 2019-03-20 11:24:56.316 ios_chrome_integration_egtests[37813:1986157] Tapping on key: Go. [11:25:04:INFO] 2019-03-20 11:25:04.599 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 1. [11:25:04:INFO] 2019-03-20 11:25:04.702 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, numbers. [11:25:04:INFO] 2019-03-20 11:25:04.755 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 1. [11:25:04:INFO] 2019-03-20 11:25:04.846 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 2. [11:25:04:INFO] 2019-03-20 11:25:04.846 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 2. [11:25:04:INFO] 2019-03-20 11:25:04.921 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 7. [11:25:04:INFO] 2019-03-20 11:25:04.922 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 7. [11:25:04:INFO] [0320/112504.968275:WARNING:spdy_session.cc(3232)] Received HEADERS for invalid stream 19 [11:25:04:INFO] 2019-03-20 11:25:04.995 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:25:04:INFO] 2019-03-20 11:25:04.997 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:25:05:INFO] 2019-03-20 11:25:05.069 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 0. [11:25:05:INFO] 2019-03-20 11:25:05.070 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 0. [11:25:05:INFO] 2019-03-20 11:25:05.145 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:25:05:INFO] 2019-03-20 11:25:05.147 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:25:05:INFO] 2019-03-20 11:25:05.225 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 0. [11:25:05:INFO] 2019-03-20 11:25:05.226 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 0. [11:25:05:INFO] 2019-03-20 11:25:05.309 ios_chrome_integration_egtests[37813:1986157] Attempting to type key .. [11:25:05:INFO] 2019-03-20 11:25:05.313 ios_chrome_integration_egtests[37813:1986157] Tapping on key: .. [11:25:05:INFO] 2019-03-20 11:25:05.386 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 1. [11:25:05:INFO] 2019-03-20 11:25:05.387 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 1. [11:25:05:INFO] 2019-03-20 11:25:05.543 ios_chrome_integration_egtests[37813:1986157] Attempting to type key :. [11:25:05:INFO] 2019-03-20 11:25:05.544 ios_chrome_integration_egtests[37813:1986157] Tapping on key: :. [11:25:05:INFO] 2019-03-20 11:25:05.619 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 6. [11:25:05:INFO] 2019-03-20 11:25:05.620 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 6. [11:25:05:INFO] 2019-03-20 11:25:05.771 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 0. [11:25:05:INFO] 2019-03-20 11:25:05.772 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 0. [11:25:05:INFO] 2019-03-20 11:25:05.846 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 5. [11:25:05:INFO] 2019-03-20 11:25:05.847 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 5. [11:25:06:INFO] 2019-03-20 11:25:06.004 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 6. [11:25:06:INFO] 2019-03-20 11:25:06.005 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 6. [11:25:06:INFO] 2019-03-20 11:25:06.080 ios_chrome_integration_egtests[37813:1986157] Attempting to type key 3. [11:25:06:INFO] 2019-03-20 11:25:06.080 ios_chrome_integration_egtests[37813:1986157] Tapping on key: 3. [11:25:06:INFO] 2019-03-20 11:25:06.192 ios_chrome_integration_egtests[37813:1986157] Attempting to type key /. [11:25:06:INFO] 2019-03-20 11:25:06.192 ios_chrome_integration_egtests[37813:1986157] Tapping on key: /. [11:25:06:INFO] 2019-03-20 11:25:06.260 ios_chrome_integration_egtests[37813:1986157] Attempting to type key t. [11:25:06:INFO] 2019-03-20 11:25:06.566 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, letters. [11:25:06:INFO] 2019-03-20 11:25:06.619 ios_chrome_integration_egtests[37813:1986157] Tapping on key: t. [11:25:06:INFO] 2019-03-20 11:25:06.687 ios_chrome_integration_egtests[37813:1986157] Attempting to type key e. [11:25:06:INFO] 2019-03-20 11:25:06.687 ios_chrome_integration_egtests[37813:1986157] Tapping on key: e. [11:25:06:INFO] 2019-03-20 11:25:06.755 ios_chrome_integration_egtests[37813:1986157] Attempting to type key s. [11:25:06:INFO] 2019-03-20 11:25:06.756 ios_chrome_integration_egtests[37813:1986157] Tapping on key: s. [11:25:06:INFO] 2019-03-20 11:25:06.822 ios_chrome_integration_egtests[37813:1986157] Attempting to type key t. [11:25:06:INFO] 2019-03-20 11:25:06.822 ios_chrome_integration_egtests[37813:1986157] Tapping on key: t. [11:25:06:INFO] 2019-03-20 11:25:06.890 ios_chrome_integration_egtests[37813:1986157] Attempting to type key -. [11:25:06:INFO] 2019-03-20 11:25:06.994 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, numbers. [11:25:07:INFO] 2019-03-20 11:25:07.048 ios_chrome_integration_egtests[37813:1986157] Tapping on key: -. [11:25:07:INFO] 2019-03-20 11:25:07.121 ios_chrome_integration_egtests[37813:1986157] Attempting to type key p. [11:25:07:INFO] 2019-03-20 11:25:07.429 ios_chrome_integration_egtests[37813:1986157] Tapping on key: more, letters. [11:25:07:INFO] 2019-03-20 11:25:07.481 ios_chrome_integration_egtests[37813:1986157] Tapping on key: p. [11:25:07:INFO] 2019-03-20 11:25:07.562 ios_chrome_integration_egtests[37813:1986157] Attempting to type key a. [11:25:07:INFO] 2019-03-20 11:25:07.562 ios_chrome_integration_egtests[37813:1986157] Tapping on key: a. [11:25:07:INFO] 2019-03-20 11:25:07.630 ios_chrome_integration_egtests[37813:1986157] Attempting to type key g. [11:25:07:INFO] 2019-03-20 11:25:07.631 ios_chrome_integration_egtests[37813:1986157] Tapping on key: g. [11:25:13:INFO] Test Case '-[PrerenderTestCase testTapPrerenderSuggestions]' passed (33.948 seconds). [11:25:13:INFO] Test Suite 'PrerenderTestCase' passed at 2019-03-20 11:25:13.858. [11:25:13:INFO] Executed 1 test, with 0 failures (0 unexpected) in 33.948 (33.956) seconds [11:25:13:INFO] Test Suite 'TabUsageRecorderTestCase' started at 2019-03-20 11:25:13.859 [11:25:13:INFO] [0320/112513.860131:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:25:13:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' started. [11:25:18:INFO] [0320/112518.487566:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [11:25:34:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadBackgrounded]' passed (20.331 seconds). [11:25:34:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' started. [11:26:09:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSettingsAndBack]' passed (34.906 seconds). [11:26:09:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' started. [11:26:31:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabReloadSwitchToNTP]' passed (22.542 seconds). [11:26:31:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' started. [11:26:55:INFO] Test Case '-[TabUsageRecorderTestCase testEvictedTabSlowReload]' passed (23.687 seconds). [11:26:55:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' started. [11:27:15:INFO] [0320/112715.679147:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:27:15:INFO] [0320/112715.705331:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:27:15:INFO] [0320/112715.713832:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:27:18:INFO] Test Case '-[TabUsageRecorderTestCase testLinkClickNavigation]' passed (23.580 seconds). [11:27:18:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' started. [11:27:25:INFO] Test Case '-[TabUsageRecorderTestCase testLiveTabReloadBackgrounded]' passed (7.080 seconds). [11:27:25:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' started. [11:27:34:INFO] Test Case '-[TabUsageRecorderTestCase testOpenFromApp]' passed (8.602 seconds). [11:27:34:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' started. [11:27:46:INFO] Test Case '-[TabUsageRecorderTestCase testOpenLinkInNewTab]' passed (11.911 seconds). [11:27:46:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' started. [11:28:02:INFO] [0320/112802.728187:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:07:INFO] [0320/112807.468077:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:11:INFO] [0320/112811.962546:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:27:INFO] [0320/112827.038332:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:27:INFO] [0320/112827.049750:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:27:INFO] [0320/112827.052388:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:27:INFO] [0320/112827.053191:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:27:INFO] [0320/112827.062198:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:27:INFO] [0320/112827.070025:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:30:INFO] Test Case '-[TabUsageRecorderTestCase testPageLoadCountBeforeEvictedTab]' passed (43.756 seconds). [11:28:30:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' started. [11:28:50:INFO] [0320/112850.742509:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:50:INFO] [0320/112850.751121:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:50:INFO] [0320/112850.751743:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:50:INFO] [0320/112850.774860:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [11:28:53:INFO] Test Case '-[TabUsageRecorderTestCase testPageRedirect]' passed (23.649 seconds). [11:28:53:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' started. [11:29:09:INFO] Test Case '-[TabUsageRecorderTestCase testTabDeletion]' passed (15.586 seconds). [11:29:09:INFO] Test Suite 'TabUsageRecorderTestCase' passed at 2019-03-20 11:29:09.499. [11:29:09:INFO] Executed 11 tests, with 0 failures (0 unexpected) in 235.629 (235.640) seconds [11:29:09:INFO] Test Suite 'UKMTestCase' started at 2019-03-20 11:29:09.501 [11:29:09:INFO] [0320/112909.502422:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:29:09:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' started. [11:29:19:INFO] 2019-03-20 11:29:19.307 ios_chrome_integration_egtests[37813:1986157] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7f88e66e3990; baseClass = UIButton; frame = (16 726; 86 36); opaque = NO; layer = <MDCShapedShadowLayer: 0x600001be8700>>, Touch Target: {86, 36} [11:29:21:INFO] [0320/112921.195680:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:29:21:INFO] [0320/112921.216942:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:29:21:INFO] [0320/112921.259471:ERROR:account_tracker.cc(264)] OnOAuthError [11:29:21:INFO] [0320/112921.259598:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:29:22:INFO] [0320/112922.539631:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:29:36:INFO] [0320/112936.605636:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:29:50:INFO] [0320/112950.018063:ERROR:account_tracker.cc(264)] OnOAuthError [11:29:50:INFO] [0320/112950.018180:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:29:51:INFO] [0320/112951.582246:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:29:51:INFO] [0320/112951.660491:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:30:06:INFO] [0320/113006.064786:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:30:06:INFO] [0320/113006.296942:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:30:06:INFO] [0320/113006.297078:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:30:10:INFO] [0320/113010.222532:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:30:12:INFO] Test Case '-[UKMTestCase testConsentAddedButNoSync]' passed (62.635 seconds). [11:30:12:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' started. [11:30:23:INFO] [0320/113023.602298:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:30:23:INFO] [0320/113023.604094:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:30:23:INFO] [0320/113023.636159:ERROR:account_tracker.cc(264)] OnOAuthError [11:30:23:INFO] [0320/113023.636273:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:30:24:INFO] [0320/113024.969204:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:30:45:INFO] [0320/113045.398162:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:30:45:INFO] [0320/113045.642087:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:30:45:INFO] [0320/113045.642217:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:30:49:INFO] [0320/113049.537241:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:30:51:INFO] Test Case '-[UKMTestCase testIncognitoPlusRegular]' passed (39.308 seconds). [11:30:51:INFO] Test Case '-[UKMTestCase testMetricsConsent]' started. [11:31:02:INFO] [0320/113102.887328:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:31:02:INFO] [0320/113102.888833:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:31:02:INFO] [0320/113102.910224:ERROR:account_tracker.cc(264)] OnOAuthError [11:31:02:INFO] [0320/113102.910361:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:31:04:INFO] [0320/113104.240091:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:31:18:INFO] [0320/113118.427603:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:31:18:INFO] [0320/113118.635500:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:31:18:INFO] [0320/113118.635659:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:31:22:INFO] [0320/113122.569359:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:31:24:INFO] Test Case '-[UKMTestCase testMetricsConsent]' passed (33.029 seconds). [11:31:24:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' started. [11:31:36:INFO] [0320/113136.138139:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:31:36:INFO] [0320/113136.139678:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:31:36:INFO] [0320/113136.174926:ERROR:account_tracker.cc(264)] OnOAuthError [11:31:36:INFO] [0320/113136.175070:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:31:37:INFO] [0320/113137.490764:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:32:02:INFO] [0320/113202.922872:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:32:03:INFO] [0320/113203.136821:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:32:03:INFO] [0320/113203.136961:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:32:07:INFO] [0320/113207.066677:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:32:09:INFO] Test Case '-[UKMTestCase testRegularPlusIncognito]' passed (44.567 seconds). [11:32:09:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' started. [11:32:20:INFO] [0320/113220.800022:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:32:20:INFO] [0320/113220.801574:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:32:20:INFO] [0320/113220.843703:ERROR:account_tracker.cc(264)] OnOAuthError [11:32:20:INFO] [0320/113220.843819:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:32:22:INFO] [0320/113222.164205:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:33:00:INFO] [0320/113300.973988:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:33:01:INFO] [0320/113301.188236:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:33:01:INFO] [0320/113301.188376:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:33:05:INFO] [0320/113305.130853:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:33:14:INFO] [0320/113314.178679:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:33:14:INFO] [0320/113314.180243:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:33:14:INFO] [0320/113314.219056:ERROR:account_tracker.cc(264)] OnOAuthError [11:33:14:INFO] [0320/113314.219174:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:33:15:INFO] [0320/113315.541988:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:33:29:INFO] [0320/113329.484685:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:33:29:INFO] [0320/113329.719430:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:33:29:INFO] [0320/113329.719591:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:33:33:INFO] [0320/113333.613383:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:33:35:INFO] Test Case '-[UKMTestCase testSecondaryPassphrase]' passed (86.483 seconds). [11:33:35:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' started. [11:33:46:INFO] [0320/113346.955447:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:33:46:INFO] [0320/113346.956904:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:33:46:INFO] [0320/113346.992671:ERROR:account_tracker.cc(264)] OnOAuthError [11:33:46:INFO] [0320/113346.992807:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:33:48:INFO] [0320/113348.325529:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:34:04:INFO] [0320/113404.768786:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:34:07:INFO] [0320/113407.118827:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:34:08:INFO] [0320/113408.940886:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:34:10:INFO] [0320/113410.745400:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:34:23:INFO] [0320/113423.388370:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:34:23:INFO] [0320/113423.597532:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:34:23:INFO] [0320/113423.597668:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:34:27:INFO] [0320/113427.527413:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:34:29:INFO] Test Case '-[UKMTestCase testSingleDisableSync]' passed (53.906 seconds). [11:34:29:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' started. [11:34:40:INFO] [0320/113440.963208:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:34:40:INFO] [0320/113440.964764:WARNING:sync_encryption_handler_impl.cc(1080)] Nigori had empty encryption keybag. [11:34:40:INFO] [0320/113440.984208:ERROR:account_tracker.cc(264)] OnOAuthError [11:34:40:INFO] [0320/113440.984336:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:34:42:INFO] [0320/113442.358496:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:34:56:INFO] [0320/113456.506627:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:34:56:INFO] [0320/113456.731013:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:34:56:INFO] [0320/113456.731152:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:35:09:INFO] [0320/113509.506717:ERROR:account_tracker.cc(264)] OnOAuthError [11:35:09:INFO] [0320/113509.506851:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [11:35:10:INFO] [0320/113510.855710:INFO:profile_sync_service.cc(1806)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [11:35:10:INFO] [0320/113510.931344:WARNING:session_sync_bridge.cc(228)] Dropping modification to local session. [11:35:25:INFO] [0320/113525.248394:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [11:35:25:INFO] [0320/113525.456344:WARNING:gaia_auth_fetcher.cc(898)] ClientLogin failed with [11:35:25:INFO] [0320/113525.456480:WARNING:gaia_auth_fetcher.cc(921)] Incomprehensible response from Google Accounts servers. [11:35:29:INFO] [0320/113529.403607:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [11:35:31:INFO] Test Case '-[UKMTestCase testSingleSyncSignout]' passed (61.877 seconds). [11:35:31:INFO] Test Suite 'UKMTestCase' passed at 2019-03-20 11:35:31.315. [11:35:31:INFO] Executed 7 tests, with 0 failures (0 unexpected) in 381.806 (381.814) seconds [11:35:31:INFO] Test Suite 'ios_chrome_integration_egtests.app' passed at 2019-03-20 11:35:31.316. [11:35:31:INFO] Executed 51 tests, with 0 failures (0 unexpected) in 984.186 (986.164) seconds [11:35:31:INFO] Test Suite 'All tests' passed at 2019-03-20 11:35:31.317. [11:35:31:INFO] Executed 51 tests, with 0 failures (0 unexpected) in 984.186 (986.166) seconds [11:35:31:INFO] [11:35:31:INFO] [11:35:31:INFO] Test session log: [11:35:31:INFO] /b/s/w/ioLWIFwC/12.1 iPhone X/attempt_0/1_Test/Diagnostics/ios_chrome_integration_egtests_module-35983AF7-A1FD-42FE-93E3-EE76AB1C8285/ios_chrome_integration_egtests_module-0A85E103-A86F-46AF-8EA6-7E34E9616EA4/Session-ios_chrome_integration_egtests_module-2019-03-20_111838-b3QPvH.log [11:35:31:INFO] [11:35:31:INFO] 2019-03-20 11:35:31.640 xcodebuild[37692:1984898] [MT] IDETestOperationsObserverDebug: 1013.093 elapsed -- Testing started completed. [11:35:31:INFO] 2019-03-20 11:35:31.640 xcodebuild[37692:1984898] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [11:35:31:INFO] 2019-03-20 11:35:31.640 xcodebuild[37692:1984898] [MT] IDETestOperationsObserverDebug: 1013.093 sec, +1013.093 sec -- end [11:35:31:INFO] ** TEST EXECUTE SUCCEEDED ** [11:35:31:INFO] [11:35:37:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioLWIFwC/run_1553105917.xctestrun', '-destination', 'platform=iOS Simulator,OS=12.1,name=iPhone X', '-resultBundlePath', '/b/s/w/ioLWIFwC/12.1 iPhone X/attempt_0'] finished with 0 [11:35:37:DEBUG] Test ended. [11:35:37:DEBUG] Extracting test data. [11:35:37:DEBUG] Retrieving crash reports. [11:35:37:DEBUG] Retrieving derived data. [11:35:37:DEBUG] Making desktop screenshots. [11:35:37:DEBUG] Killing simulators. [11:35:42:DEBUG] Wiping simulator. Device wiped. [11:35:45:DEBUG] End of tear_down. 2019-03-20 11:43:02,365 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/3O12oRl/43b4b8dbd23eaf10'] 2019-03-20 11:43:02,365 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/3O12oRl/43b4b8dbd23eaf10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/3O12oRl/43b4b8dbd23eaf10/output.json'] 2019-03-20 11:43:02,365 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/3O12oRl' Found ['/b/s/w/ir/k/recipe_cleanup/3O12oRl/43b4b8dbd23eaf10'] 2019-03-20 11:43:02,365 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/3O12oRl/43b4b8dbd23eaf10/output.json'] 2019-03-20 11:43:02,366 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmp829Qjs.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/3O12oRl -o /b/s/w/ir/tmp/t/tmpxMG5Ac.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmp829Qjs.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/3O12oRl', '-o', '/b/s/w/ir/tmp/t/tmpxMG5Ac.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmp829Qjs.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/3O12oRl', '-o', '/b/s/w/ir/tmp/t/tmpxMG5Ac.json'] returned exit code 0