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/tmpzAhGQf.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/28_1T0wP7a --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/tmppGYHmimerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmp6zFjcx.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/tmpDXqneX.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/tmpzAhGQf.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a', '--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/tmppGYHmimerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmp6zFjcx.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/tmpDXqneX.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('ios_chrome_ui_egtests (iPad Air 2 iOS 11.4) shard 1 on Mac-10.13.6',) ok_ret: ALL_OK step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.5cx1mQk0o5/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: 55105 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/8918110889687376480 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itsRtj7P/luci_context.339172610 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.oXb7nOiNGD/Listeners SWARMING_BOT_ID: build111-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43c8165ef405ae11 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-24 04:19:26,605 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a' 2019-03-24 04:19:26,605 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a' already exists! 2019-03-24 04:19:26,605 - root: [WARNING] task_output_dir existing content: [] 2019-03-24 04:19:26,605 - 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/tmpDXqneX.json -output-dir /b/s/w/ir/k/recipe_cleanup/28_1T0wP7a -task-summary-json /b/s/w/ir/tmp/t/tmp6zFjcx.json [D2019-03-24T04:19:26.619922-07:00 83260 0 auth.go:1265] Minting a new token {"key":"luci_ctx/641796a89de195fb286ffa0511863f0137181443bbd419b7ad814b54401d0f44", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-24T04:19:26.619994-07:00 83260 0 luci_ctx.go:138] POST http://127.0.0.1:55094/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/641796a89de195fb286ffa0511863f0137181443bbd419b7ad814b54401d0f44", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-24T04:19:26.621393-07:00 83260 0 auth.go:1222] Token expires in 57m42.37862s {"key":"luci_ctx/641796a89de195fb286ffa0511863f0137181443bbd419b7ad814b54401d0f44", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 43c8201ad8b8bd10: exit 0 [I 2019-03-24 03:46:11] About to install Xcode 10b61 in Xcode.app for ios [03:46:12:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer [03:46:20:INFO] Start test attempt #0 for command [xcodebuild test-without-building -xctestrun /b/s/w/ioUZAEGM/run_1553424380.xctestrun -destination platform=iOS Simulator,OS=11.4,name=iPad Air 2 -resultBundlePath /b/s/w/ioUZAEGM/11.4 iPad Air 2/attempt_0] [03:46:20:INFO] User defaults from command line: Erasing all simulators. Launching ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioUZAEGM/run_1553424380.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPad Air 2', '-resultBundlePath', '/b/s/w/ioUZAEGM/11.4 iPad Air 2/attempt_0'] with env {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'MAC_CHROMIUM_TMPDIR': '/b/s/w/it8tovzF', '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': 'build766-m4', 'VIRTUAL_ENV': '/b/s/w/ir/.swarming_module_cache/vpython/b6ec90', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fhM7eKwWo9/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/it8tovzF/luci_ctx.MF0lHr.json', 'CIPD_CACHE_DIR': '/b/s/cipd_cache/cache', 'XPC_SERVICE_NAME': 'org.swarm.bot.plist', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fUDB5HBBAQ/Listeners', 'VPYTHON_VIRTUALENV_ROOT': '/b/s/w/ir/.swarming_module_cache/vpython', 'SWARMING_TASK_ID': '43c8201ad8b8bd11', 'SHELL': '/bin/bash', 'NO_GCE_CHECK': 'False', 'TMPDIR': '/b/s/w/it8tovzF', 'CIPD_PROTOCOL': 'v2', '__CF_USER_TEXT_ENCODING': '0x1F4:0x0:0x0', 'NSUnbufferedIO': 'YES'} [03:46:20:INFO] IDEBuildOperationResultBundlePath = /b/s/w/ioUZAEGM/11.4 iPad Air 2/attempt_0 [03:46:20:INFO] IDETestRunSpecificationPath = /b/s/w/ioUZAEGM/run_1553424380.xctestrun [03:46:20:INFO] [03:46:20:INFO] Testing started on 'iPad Air 2' [03:46:20:INFO] 2019-03-24 03:46:20.921 xcodebuild[3848:42441] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [03:46:20:INFO] /b/s/w/ioUZAEGM/11.4 iPad Air 2/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-30FFDAF6-A9D0-4EE4-AF0E-C3E017CA18CE/ios_chrome_ui_egtests_module-F7FD9BC5-C951-4138-91F6-89364C1608F0/Session-ios_chrome_ui_egtests_module-2019-03-24_034620-NQ3x09.log [03:46:20:INFO] 2019-03-24 03:46:20.921 xcodebuild[3848:42385] [MT] IDETestOperationsObserverDebug: (51AA1796-0044-4DC4-A0ED-8E5476A12E54) Beginning test session ios_chrome_ui_egtests_module-51AA1796-0044-4DC4-A0ED-8E5476A12E54 at 2019-03-24 03:46:20.922 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fef65001660> { [03:46:20:INFO] SimDevice: iPad Air 2 (36FAA98D-E2A2-4DE3-82CB-AB20149BC26C, iOS 11.4, Shutdown) [03:46:20:INFO] } (11.4 (15F79)) [03:46:42:INFO] 2019-03-24 03:46:42.345 ios_chrome_ui_egtests[3964:43839] Crash handler setup started. [03:46:42:INFO] 2019-03-24 03:46:42.346 ios_chrome_ui_egtests[3964:43839] Crash handler setup completed. [03:46:42:INFO] 2019-03-24 03:46:42.346 ios_chrome_ui_egtests[3964:43839] Enabling accessibility for automation on Simulator. [03:46:42:INFO] objc[3964]: Class VCWeakObjectHolder is implemented in both /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/Frameworks/ViceroyTrace.framework/ViceroyTrace (0x13e58e4d0) and /b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/Library/CoreSimulator/Profiles/Runtimes/iOS 11.4.simruntime/Contents/Resources/RuntimeRoot/System/Library/PrivateFrameworks/AVConference.framework/AVConference (0x13e3a4e38). One of the two will be used. Which one is undefined. [03:46:45:INFO] 2019-03-24 03:46:45.855 ios_chrome_ui_egtests[3964:43839] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7fc48d859a00>. [03:46:49:INFO] [0324/034649.665312:WARNING:leveldb_database.cc(106)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/36FAA98D-E2A2-4DE3-82CB-AB20149BC26C/data/Containers/Data/Application/4D8166BE-4C6C-43DC-A48B-9D0702FFC195/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/36FAA98D-E2A2-4DE3-82CB-AB20149BC26C/data/Containers/Data/Application/4D8166BE-4C6C-43DC-A48B-9D0702FFC195/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [03:46:53:INFO] Test Suite 'Selected tests' started at 2019-03-24 03:46:53.255 [03:46:53:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' started at 2019-03-24 03:46:53.256 [03:46:53:INFO] Test Suite 'FirstRunTestCase' started at 2019-03-24 03:46:53.256 [03:46:53:INFO] [0324/034653.263638:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:46:55:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' started. [03:47:03:INFO] Test Case '-[FirstRunTestCase testDismissFirstRun]' passed (8.779 seconds). [03:47:03:INFO] Test Case '-[FirstRunTestCase testPrivacy]' started. [03:47:11:INFO] Test Case '-[FirstRunTestCase testPrivacy]' passed (7.341 seconds). [03:47:11:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' started. [03:47:15:INFO] [0324/034715.352830:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag. [03:47:15:INFO] [0324/034715.373383:ERROR:account_tracker.cc(264)] OnOAuthError [03:47:15:INFO] [0324/034715.373512:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [03:47:15:INFO] [0324/034715.379101:WARNING:sync_encryption_handler_impl.cc(1086)] Nigori had empty encryption keybag. [03:47:19:INFO] [0324/034719.522806:INFO:profile_sync_service.cc(1822)] ConfigureDataTypeManager not invoked because datatypes cannot be configured now [03:47:20:INFO] [0324/034720.167959:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [03:47:22:INFO] Test Case '-[FirstRunTestCase testSignInAndTapSettingsLink]' passed (10.881 seconds). [03:47:22:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' started. [03:47:26:INFO] [0324/034726.276288:ERROR:account_tracker.cc(264)] OnOAuthError [03:47:26:INFO] [0324/034726.276468:WARNING:account_tracker.cc(190)] Failed to get UserInfo for foo1ID [03:47:27:INFO] [0324/034727.333724:INFO:signin_manager.cc(128)] Revoking all refresh tokens on server. Reason: sign out, IsSigninAllowed: 1 [03:47:31:INFO] Test Case '-[FirstRunTestCase testSignInAndUndo]' passed (9.851 seconds). [03:47:31:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' started. [03:47:39:INFO] Test Case '-[FirstRunTestCase testTermsAndConditions]' passed (7.352 seconds). [03:47:39:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' started. [03:47:45:INFO] Test Case '-[FirstRunTestCase testToggleMetricsOn]' passed (6.555 seconds). [03:47:45:INFO] Test Suite 'FirstRunTestCase' passed at 2019-03-24 03:47:45.874. [03:47:45:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 50.759 (52.618) seconds [03:47:45:INFO] Test Suite 'FullscreenTestCase' started at 2019-03-24 03:47:45.875 [03:47:45:INFO] [0324/034745.876709:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:47:45:INFO] Test Case '-[FullscreenTestCase testChromeToChromeURLKeepsHeaderOnScreen]' started. [03:47:55:INFO] Test Case '-[FullscreenTestCase testChromeToChromeURLKeepsHeaderOnScreen]' passed (9.943 seconds). [03:47:55:INFO] Test Case '-[FullscreenTestCase testHideHeaderUserScrollLongPage]' started. [03:47:57:INFO] [0324/034757.866401:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:48:04:INFO] Test Case '-[FullscreenTestCase testHideHeaderUserScrollLongPage]' passed (8.772 seconds). [03:48:04:INFO] Test Case '-[FullscreenTestCase testLongPDFInitialState]' started. [03:48:09:INFO] Test Case '-[FullscreenTestCase testLongPDFInitialState]' passed (5.270 seconds). [03:48:09:INFO] Test Case '-[FullscreenTestCase testLongPDFScroll]' started. [03:48:11:INFO] 2019-03-24 03:48:11.635 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Disabled on iOS 11. [03:48:13:INFO] Test Case '-[FullscreenTestCase testLongPDFScroll]' passed (3.615 seconds). [03:48:13:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnErrorPage]' started. [03:48:21:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnErrorPage]' passed (7.905 seconds). [03:48:21:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnNativePageLoad]' started. [03:48:29:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnNativePageLoad]' passed (8.064 seconds). [03:48:29:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnRegularPageLoad]' started. [03:48:40:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnRegularPageLoad]' passed (11.108 seconds). [03:48:40:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnReload]' started. [03:48:48:INFO] Test Case '-[FullscreenTestCase testShowHeaderOnReload]' passed (7.896 seconds). [03:48:48:INFO] Test Case '-[FullscreenTestCase testShowHeaderWhenChildTabCloses]' started. [03:48:53:INFO] [0324/034853.563899:WARNING:web_state_impl.mm(657)] Script execution has failed: A JavaScript exception occurred [03:48:59:INFO] Test Case '-[FullscreenTestCase testShowHeaderWhenChildTabCloses]' passed (10.535 seconds). [03:48:59:INFO] Test Case '-[FullscreenTestCase testSmallWidePDFScroll]' started. [03:49:07:INFO] Test Case '-[FullscreenTestCase testSmallWidePDFScroll]' passed (8.871 seconds). [03:49:07:INFO] Test Suite 'FullscreenTestCase' passed at 2019-03-24 03:49:07.874. [03:49:07:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 81.979 (81.999) seconds [03:49:07:INFO] Test Suite 'HistoryUITestCase' started at 2019-03-24 03:49:07.875 [03:49:07:INFO] [0324/034907.875979:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:49:07:INFO] Test Case '-[HistoryUITestCase testAccessibilityOnHistory]' started. [03:49:10:INFO] [0324/034910.073325:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:49:19:INFO] Test Case '-[HistoryUITestCase testAccessibilityOnHistory]' passed (11.974 seconds). [03:49:19:INFO] Test Case '-[HistoryUITestCase testClearBrowsingHistory]' started. [03:49:39:INFO] Test Case '-[HistoryUITestCase testClearBrowsingHistory]' passed (19.713 seconds). [03:49:39:INFO] Test Case '-[HistoryUITestCase testContextMenuCopy]' started. [03:49:51:INFO] 2019-03-24 03:49:51.444 ios_chrome_ui_egtests[3964:43869] PBItemCollectionServicer connection disconnected. [03:49:53:INFO] Test Case '-[HistoryUITestCase testContextMenuCopy]' passed (14.163 seconds). [03:49:53:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]' started. [03:50:09:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewIncognitoTab]' passed (15.366 seconds). [03:50:09:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewTab]' started. [03:50:24:INFO] Test Case '-[HistoryUITestCase testContextMenuOpenInNewTab]' passed (15.041 seconds). [03:50:24:INFO] Test Case '-[HistoryUITestCase testDeleteHistory]' started. [03:50:42:INFO] Test Case '-[HistoryUITestCase testDeleteHistory]' passed (18.267 seconds). [03:50:42:INFO] Test Case '-[HistoryUITestCase testDisplayHistory]' started. [03:50:56:INFO] Test Case '-[HistoryUITestCase testDisplayHistory]' passed (13.874 seconds). [03:50:56:INFO] Test Case '-[HistoryUITestCase testDisplayNoHistory]' started. [03:51:05:INFO] Test Case '-[HistoryUITestCase testDisplayNoHistory]' passed (8.941 seconds). [03:51:05:INFO] Test Case '-[HistoryUITestCase testHistoryUpdateAfterBackNavigation]' started. [03:51:17:INFO] Test Case '-[HistoryUITestCase testHistoryUpdateAfterBackNavigation]' passed (12.575 seconds). [03:51:17:INFO] Test Case '-[HistoryUITestCase testSearchHistory]' started. [03:51:19:INFO] 2019-03-24 03:51:19.733 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Test disabled on iPad. [03:51:21:INFO] Test Case '-[HistoryUITestCase testSearchHistory]' passed (3.850 seconds). [03:51:21:INFO] Test Case '-[HistoryUITestCase testSearchLongPressOnScrimCancelsSearchController]' started. [03:51:31:INFO] 2019-03-24 03:51:31.448 ios_chrome_ui_egtests[3964:43839] +[CATransaction synchronize] called within transaction [03:51:31:INFO] 2019-03-24 03:51:31.467 ios_chrome_ui_egtests[3964:43839] +[CATransaction synchronize] called within transaction [03:51:31:INFO] 2019-03-24 03:51:31.467 ios_chrome_ui_egtests[3964:43839] +[CATransaction synchronize] called within transaction [03:51:31:INFO] 2019-03-24 03:51:31.467 ios_chrome_ui_egtests[3964:43839] +[CATransaction synchronize] called within transaction [03:51:38:INFO] Test Case '-[HistoryUITestCase testSearchLongPressOnScrimCancelsSearchController]' passed (16.980 seconds). [03:51:38:INFO] Test Suite 'HistoryUITestCase' passed at 2019-03-24 03:51:38.634. [03:51:38:INFO] Executed 11 tests, with 0 failures (0 unexpected) in 150.745 (150.760) seconds [03:51:38:INFO] Test Suite 'InfobarTestCase' started at 2019-03-24 03:51:38.635 [03:51:38:INFO] [0324/035138.636366:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:51:38:INFO] Test Case '-[InfobarTestCase testInfobarButtonDismissal]' started. [03:51:40:INFO] [0324/035140.651347:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:51:45:INFO] 2019-03-24 03:51:45.997 ios_chrome_ui_egtests[3964:43839] Button touch target does not meet minimum size guidlines of (48, 48). Button: <MDCFlatButton: 0x7fc494178560; baseClass = UIButton; frame = (656 16; 64 36); opaque = NO; tag = 1; layer = <MDCShapedShadowLayer: 0x604000aedd00>>, Touch Target: {64, 36} [03:51:48:INFO] Test Case '-[InfobarTestCase testInfobarButtonDismissal]' passed (9.488 seconds). [03:51:48:INFO] Test Case '-[InfobarTestCase testInfobarsDismissOnNavigate]' started. [03:51:58:INFO] Test Case '-[InfobarTestCase testInfobarsDismissOnNavigate]' passed (10.137 seconds). [03:51:58:INFO] Test Case '-[InfobarTestCase testInfobarTabSwitch]' started. [03:52:15:INFO] Test Case '-[InfobarTestCase testInfobarTabSwitch]' passed (17.384 seconds). [03:52:15:INFO] Test Case '-[InfobarTestCase testInfobarTallerLayout]' started. [03:52:25:INFO] Test Case '-[InfobarTestCase testInfobarTallerLayout]' passed (9.399 seconds). [03:52:25:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisible]' started. [03:52:38:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisible]' passed (13.488 seconds). [03:52:38:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisibleHeight]' started. [03:52:56:INFO] Test Case '-[InfobarTestCase testInfobarTopMostVisibleHeight]' passed (17.868 seconds). [03:52:56:INFO] Test Suite 'InfobarTestCase' passed at 2019-03-24 03:52:56.408. [03:52:56:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 77.764 (77.773) seconds [03:52:56:INFO] Test Suite 'InspectUITestCase' started at 2019-03-24 03:52:56.409 [03:52:56:INFO] [0324/035256.410309:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:52:56:INFO] Test Case '-[InspectUITestCase testIframeLogging]' started. [03:53:00:INFO] [0324/035300.985536:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:53:04:INFO] Test Case '-[InspectUITestCase testIframeLogging]' passed (7.788 seconds). [03:53:04:INFO] Test Case '-[InspectUITestCase testLoggingFromMultipleTabs]' started. [03:53:08:INFO] [0324/035308.755057:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:53:14:INFO] Test Case '-[InspectUITestCase testLoggingFromMultipleTabs]' passed (10.569 seconds). [03:53:14:INFO] Test Case '-[InspectUITestCase testMainFrameLogging]' started. [03:53:19:INFO] [0324/035319.274442:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:53:22:INFO] Test Case '-[InspectUITestCase testMainFrameLogging]' passed (7.745 seconds). [03:53:22:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnReload]' started. [03:53:27:INFO] [0324/035327.059166:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:53:31:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnReload]' passed (8.781 seconds). [03:53:31:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnStopLogging]' started. [03:53:35:INFO] [0324/035335.823515:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:53:38:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnStopLogging]' passed (7.670 seconds). [03:53:38:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnTabClosure]' started. [03:53:43:INFO] [0324/035343.486938:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:53:46:INFO] Test Case '-[InspectUITestCase testMessagesClearedOnTabClosure]' passed (7.939 seconds). [03:53:46:INFO] Test Case '-[InspectUITestCase testStartStopLogging]' started. [03:53:51:INFO] Test Case '-[InspectUITestCase testStartStopLogging]' passed (4.867 seconds). [03:53:51:INFO] Test Suite 'InspectUITestCase' passed at 2019-03-24 03:53:51.779. [03:53:51:INFO] Executed 7 tests, with 0 failures (0 unexpected) in 55.359 (55.370) seconds [03:53:51:INFO] Test Suite 'JavaScriptDialogTestCase' started at 2019-03-24 03:53:51.780 [03:53:51:INFO] [0324/035351.781194:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:53:51:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterNewTabAnimation]' started. [03:53:53:INFO] [0324/035353.827943:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:54:02:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterNewTabAnimation]' passed (10.378 seconds). [03:54:02:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterShareMenu]' started. [03:54:03:INFO] 2019-03-24 03:54:03.910 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Disabled until EG can use share menu. [03:54:05:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAfterShareMenu]' passed (3.677 seconds). [03:54:05:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlert]' started. [03:54:07:INFO] [0324/035407.802039:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:54:12:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlert]' passed (6.513 seconds). [03:54:12:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlertLoop]' started. [03:54:14:INFO] [0324/035414.357350:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:54:21:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptAlertLoop]' passed (8.951 seconds). [03:54:21:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptBehindSettings]' started. [03:54:23:INFO] [0324/035423.388235:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:54:33:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptBehindSettings]' passed (11.744 seconds). [03:54:33:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationCancelled]' started. [03:54:35:INFO] [0324/035435.036062:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:54:39:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationCancelled]' passed (6.548 seconds). [03:54:39:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationOK]' started. [03:54:41:INFO] [0324/035441.583906:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:54:46:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptConfirmationOK]' passed (6.552 seconds). [03:54:46:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptCancelled]' started. [03:54:47:INFO] 2019-03-24 03:54:47.926 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Test disabled on iPad. [03:54:49:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptCancelled]' passed (3.695 seconds). [03:54:49:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptOK]' started. [03:54:51:INFO] 2019-03-24 03:54:51.631 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Test disabled on iPad. [03:54:53:INFO] Test Case '-[JavaScriptDialogTestCase testShowJavaScriptPromptOK]' passed (3.711 seconds). [03:54:53:INFO] Test Suite 'JavaScriptDialogTestCase' passed at 2019-03-24 03:54:53.559. [03:54:53:INFO] Executed 9 tests, with 0 failures (0 unexpected) in 61.769 (61.779) seconds [03:54:53:INFO] Test Suite 'KeyboardCommandsTestCase' started at 2019-03-24 03:54:53.560 [03:54:53:INFO] [0324/035453.561065:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:54:53:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommands_RecentTabsPresented]' started. [03:55:02:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommands_RecentTabsPresented]' passed (8.600 seconds). [03:55:02:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_AddBookmarkPresented]' started. [03:55:04:INFO] [0324/035504.140056:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:55:11:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_AddBookmarkPresented]' passed (9.380 seconds). [03:55:11:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_BookmarksPresented]' started. [03:55:20:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_BookmarksPresented]' passed (8.519 seconds). [03:55:20:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_SettingsPresented]' started. [03:55:28:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsNotRegistered_SettingsPresented]' passed (8.823 seconds). [03:55:28:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsRegistered]' started. [03:55:32:INFO] Test Case '-[KeyboardCommandsTestCase testKeyboardCommandsRegistered]' passed (3.940 seconds). [03:55:32:INFO] Test Case '-[KeyboardCommandsTestCase testWebViewIsFirstResponderUponKeyPress]' started. [03:55:38:INFO] Test Case '-[KeyboardCommandsTestCase testWebViewIsFirstResponderUponKeyPress]' passed (5.234 seconds). [03:55:38:INFO] Test Suite 'KeyboardCommandsTestCase' passed at 2019-03-24 03:55:38.065. [03:55:38:INFO] Executed 6 tests, with 0 failures (0 unexpected) in 44.496 (44.506) seconds [03:55:38:INFO] Test Suite 'NTPHomeTestCase' started at 2019-03-24 03:55:38.066 [03:55:38:INFO] [0324/035538.068302:WARNING:loopback_server.cc(820)] Loopback sync persistent state file does not exist. [03:55:38:INFO] Test Case '-[NTPHomeTestCase testAccessibility]' started. [03:55:41:INFO] Test Case '-[NTPHomeTestCase testAccessibility]' passed (3.767 seconds). [03:55:41:INFO] Test Case '-[NTPHomeTestCase testCollectionShortcuts]' started. [03:55:57:INFO] Test Case '-[NTPHomeTestCase testCollectionShortcuts]' passed (16.142 seconds). [03:55:57:INFO] Test Case '-[NTPHomeTestCase testFavicons]' started. [03:56:20:INFO] Test Case '-[NTPHomeTestCase testFavicons]' passed (22.960 seconds). [03:56:20:INFO] Test Case '-[NTPHomeTestCase testOmniboxPinnedWidthRotation]' started. [03:56:22:INFO] 2019-03-24 03:56:22.695 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Disabled for iPad due to device rotation bug. [03:56:24:INFO] Test Case '-[NTPHomeTestCase testOmniboxPinnedWidthRotation]' passed (3.618 seconds). [03:56:24:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotation]' started. [03:56:26:INFO] 2019-03-24 03:56:26.316 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Disabled for iPad due to device rotation bug. [03:56:28:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotation]' passed (3.620 seconds). [03:56:28:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotationBehindSettings]' started. [03:56:29:INFO] 2019-03-24 03:56:29.935 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Disabled for iPad due to device rotation bug. [03:56:31:INFO] Test Case '-[NTPHomeTestCase testOmniboxWidthRotationBehindSettings]' passed (3.617 seconds). [03:56:31:INFO] Test Case '-[NTPHomeTestCase testOpeningNewTab]' started. [03:56:44:INFO] Test Case '-[NTPHomeTestCase testOpeningNewTab]' passed (12.420 seconds). [03:56:44:INFO] Test Case '-[NTPHomeTestCase testOpenMultipleTabs]' started. [03:57:27:INFO] Test Case '-[NTPHomeTestCase testOpenMultipleTabs]' passed (43.421 seconds). [03:57:27:INFO] Test Case '-[NTPHomeTestCase testPositionRestored]' started. [03:57:29:INFO] [03:57:29:INFO] GMOCK WARNING: [03:57:29:INFO] Uninteresting mock function call - returning directly. [03:57:29:INFO] Function call: ClearHistory(1601-01-01 00:00:00.000 UTC, 293878-01-10 04:00:54.775 UTC, @0x7ffeee518278 8-byte object <50-07 04-02 00-60 00-00>) [03:57:29:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:29:INFO] [0324/035729.839587:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:57:35:INFO] [03:57:35:INFO] GMOCK WARNING: [03:57:35:INFO] Uninteresting mock function call - returning directly. [03:57:35:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee517a38 10001|chromium1, @0x7ffeee517798 8-byte object <30-5F 65-01 40-60 00-00>) [03:57:35:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:35:INFO] [03:57:35:INFO] GMOCK WARNING: [03:57:35:INFO] Uninteresting mock function call - returning directly. [03:57:35:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee517a38 10001|chromium2, @0x7ffeee517798 8-byte object <80-61 A4-01 00-60 00-00>) [03:57:35:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:35:INFO] [03:57:35:INFO] GMOCK WARNING: [03:57:35:INFO] Uninteresting mock function call - returning directly. [03:57:35:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee517a38 10001|chromium3, @0x7ffeee517798 8-byte object <00-CC 84-01 00-60 00-00>) [03:57:35:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:36:INFO] [03:57:36:INFO] GMOCK WARNING: [03:57:36:INFO] Uninteresting mock function call - returning directly. [03:57:36:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee513d98 10001|chromium4, @0x7ffeee513af8 8-byte object <A0-49 65-00 00-60 00-00>) [03:57:36:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:39:INFO] [0324/035739.166585:WARNING:crw_web_controller.mm(2734)] Unexpected message received: console.msg [03:57:39:INFO] [0324/035739.166721:WARNING:crw_web_controller.mm(2786)] Message from JS not handled due to invalid format [03:57:39:INFO] [03:57:39:INFO] GMOCK WARNING: [03:57:39:INFO] Uninteresting mock function call - returning directly. [03:57:39:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium1, @0x7ffeee514398 8-byte object <A0-71 84-02 00-60 00-00>) [03:57:39:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:39:INFO] [03:57:39:INFO] GMOCK WARNING: [03:57:39:INFO] Uninteresting mock function call - returning directly. [03:57:39:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium2, @0x7ffeee514398 8-byte object <90-D2 44-01 40-60 00-00>) [03:57:39:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:39:INFO] [03:57:39:INFO] GMOCK WARNING: [03:57:39:INFO] Uninteresting mock function call - returning directly. [03:57:39:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium3, @0x7ffeee514398 8-byte object <C0-99 24-01 40-60 00-00>) [03:57:39:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:39:INFO] [03:57:39:INFO] GMOCK WARNING: [03:57:39:INFO] Uninteresting mock function call - returning directly. [03:57:39:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium4, @0x7ffeee514398 8-byte object <D0-1F 25-01 40-60 00-00>) [03:57:39:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:42:INFO] Test Case '-[NTPHomeTestCase testPositionRestored]' passed (14.953 seconds). [03:57:42:INFO] Test Case '-[NTPHomeTestCase testPositionRestoredWithOmniboxFocused]' started. [03:57:44:INFO] [03:57:44:INFO] GMOCK WARNING: [03:57:44:INFO] Uninteresting mock function call - returning directly. [03:57:44:INFO] Function call: ClearHistory(1601-01-01 00:00:00.000 UTC, 293878-01-10 04:00:54.775 UTC, @0x7ffeee518278 8-byte object <80-E4 05-02 00-60 00-00>) [03:57:44:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:44:INFO] [0324/035744.762068:WARNING:embedded_test_server.cc(238)] Request not handled. Returning 404: /favicon.ico [03:57:50:INFO] [03:57:50:INFO] GMOCK WARNING: [03:57:50:INFO] Uninteresting mock function call - returning directly. [03:57:50:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee517a38 10001|chromium1, @0x7ffeee517798 8-byte object <10-7C 05-02 00-60 00-00>) [03:57:50:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:50:INFO] [03:57:50:INFO] GMOCK WARNING: [03:57:50:INFO] Uninteresting mock function call - returning directly. [03:57:50:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee517a38 10001|chromium2, @0x7ffeee517798 8-byte object <30-27 64-02 00-60 00-00>) [03:57:50:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:50:INFO] [03:57:50:INFO] GMOCK WARNING: [03:57:50:INFO] Uninteresting mock function call - returning directly. [03:57:50:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee517a38 10001|chromium3, @0x7ffeee517798 8-byte object <60-AE A4-01 00-60 00-00>) [03:57:50:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:51:INFO] [03:57:51:INFO] GMOCK WARNING: [03:57:51:INFO] Uninteresting mock function call - returning directly. [03:57:51:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee513d98 10001|chromium4, @0x7ffeee513af8 8-byte object <30-FE 45-02 40-60 00-00>) [03:57:51:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:52:INFO] [03:57:52:INFO] GMOCK WARNING: [03:57:52:INFO] Uninteresting mock function call - returning directly. [03:57:52:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee513a68 10001|chromium5, @0x7ffeee5137c8 8-byte object <40-62 84-00 00-60 00-00>) [03:57:52:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:52:INFO] [03:57:52:INFO] GMOCK WARNING: [03:57:52:INFO] Uninteresting mock function call - returning directly. [03:57:52:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee513a68 10001|chromium6, @0x7ffeee5137c8 8-byte object <10-3D A5-02 00-60 00-00>) [03:57:52:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:52:INFO] [03:57:52:INFO] GMOCK WARNING: [03:57:52:INFO] Uninteresting mock function call - returning directly. [03:57:52:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee513a68 10001|chromium7, @0x7ffeee5137c8 8-byte object <50-60 A5-01 00-60 00-00>) [03:57:52:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:53:INFO] [0324/035753.694349:WARNING:crw_web_controller.mm(2734)] Unexpected message received: console.msg [03:57:53:INFO] [0324/035753.694539:WARNING:crw_web_controller.mm(2786)] Message from JS not handled due to invalid format [03:57:56:INFO] [03:57:56:INFO] GMOCK WARNING: [03:57:56:INFO] Uninteresting mock function call - returning directly. [03:57:56:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium1, @0x7ffeee514398 8-byte object <80-1C C4-01 40-60 00-00>) [03:57:56:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:56:INFO] [03:57:56:INFO] GMOCK WARNING: [03:57:56:INFO] Uninteresting mock function call - returning directly. [03:57:56:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium2, @0x7ffeee514398 8-byte object <70-43 E5-01 00-60 00-00>) [03:57:56:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:56:INFO] [03:57:56:INFO] GMOCK WARNING: [03:57:56:INFO] Uninteresting mock function call - returning directly. [03:57:56:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium3, @0x7ffeee514398 8-byte object <50-F7 24-01 40-60 00-00>) [03:57:56:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:57:56:INFO] [03:57:56:INFO] GMOCK WARNING: [03:57:56:INFO] Uninteresting mock function call - returning directly. [03:57:56:INFO] Function call: FetchSuggestionImageMock(@0x7ffeee514638 10001|chromium4, @0x7ffeee514398 8-byte object <E0-CA 64-00 40-60 00-00>) [03:57:56:INFO] NOTE: You can safely ignore the above warning unless this call should not happen. Do not suppress it by blindly adding an EXPECT_CALL() if you don't mean to enforce the call. See https://github.com/google/googletest/blob/master/googlemock/docs/CookBook.md#knowing-when-to-expect for details. [03:58:00:INFO] Test Case '-[NTPHomeTestCase testPositionRestoredWithOmniboxFocused]' passed (17.643 seconds). [03:58:00:INFO] Test Case '-[NTPHomeTestCase testPromoTap]' started. [03:58:10:INFO] Test Case '-[NTPHomeTestCase testPromoTap]' passed (10.441 seconds). [03:58:10:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmnibox]' started. [03:58:12:INFO] 2019-03-24 03:58:12.470 ios_chrome_ui_egtests[3964:43839] -- Earl Grey Test Disabled -- Test disabled on iPad. [03:58:14:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmnibox]' passed (3.640 seconds). [03:58:14:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScroll]' started. [03:58:22:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScroll]' passed (8.011 seconds). [03:58:22:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScrollScrolled]' started. [03:58:31:INFO] Test Case '-[NTPHomeTestCase testTapFakeOmniboxScrollScrolled]' passed (9.272 seconds). [03:58:31:INFO] Test Case '-[NTPHomeTestCase testTapOmniboxSearchButtonLogsCorrectly]' started. [03:58:35:INFO] Test Case '-[NTPHomeTestCase testTapOmniboxSearchButtonLogsCorrectly]' passed (3.679 seconds). [03:58:35:INFO] Test Case '-[NTPHomeTestCase testTapSearchButtonFakeOmniboxScrolled]' started. [03:58:38:INFO] Test Case '-[NTPHomeTestCase testTapSearchButtonFakeOmniboxScrolled]' passed (3.604 seconds). [03:58:38:INFO] Test Suite 'NTPHomeTestCase' passed at 2019-03-24 03:58:38.906. [03:58:38:INFO] Executed 16 tests, with 0 failures (0 unexpected) in 180.806 (180.840) seconds [03:58:38:INFO] Test Suite 'ios_chrome_ui_egtests_module.xctest' passed at 2019-03-24 03:58:38.907. [03:58:38:INFO] Executed 71 tests, with 0 failures (0 unexpected) in 703.678 (705.651) seconds [03:58:38:INFO] Test Suite 'Selected tests' passed at 2019-03-24 03:58:38.908. [03:58:38:INFO] Executed 71 tests, with 0 failures (0 unexpected) in 703.678 (705.653) seconds [03:58:39:INFO] [03:58:39:INFO] [03:58:39:INFO] Test session log: [03:58:39:INFO] /b/s/w/ioUZAEGM/11.4 iPad Air 2/attempt_0/1_Test/Diagnostics/ios_chrome_ui_egtests_module-30FFDAF6-A9D0-4EE4-AF0E-C3E017CA18CE/ios_chrome_ui_egtests_module-F7FD9BC5-C951-4138-91F6-89364C1608F0/Session-ios_chrome_ui_egtests_module-2019-03-24_034620-NQ3x09.log [03:58:39:INFO] [03:58:39:INFO] 2019-03-24 03:58:39.353 xcodebuild[3848:42385] [MT] IDETestOperationsObserverDebug: 738.569 elapsed -- Testing started completed. [03:58:39:INFO] 2019-03-24 03:58:39.353 xcodebuild[3848:42385] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [03:58:39:INFO] 2019-03-24 03:58:39.353 xcodebuild[3848:42385] [MT] IDETestOperationsObserverDebug: 738.569 sec, +738.569 sec -- end [03:58:39:INFO] ** TEST EXECUTE SUCCEEDED ** [03:58:39:INFO] [03:58:43:INFO] Command ['xcodebuild', 'test-without-building', '-xctestrun', '/b/s/w/ioUZAEGM/run_1553424380.xctestrun', '-destination', 'platform=iOS Simulator,OS=11.4,name=iPad Air 2', '-resultBundlePath', '/b/s/w/ioUZAEGM/11.4 iPad Air 2/attempt_0'] finished with 0 [03:58:43:DEBUG] Test ended. [03:58:43:DEBUG] Extracting test data. [03:58:43:DEBUG] Retrieving crash reports. [03:58:43:DEBUG] Retrieving derived data. [03:58:43:DEBUG] Making desktop screenshots. [03:58:43:DEBUG] Killing simulators. [03:58:48:DEBUG] Wiping simulator. Device wiped. [03:58:51:DEBUG] End of tear_down. 2019-03-24 04:19:27,708 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a/43c8201ad8b8bd10'] 2019-03-24 04:19:27,709 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a/43c8201ad8b8bd10/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a/43c8201ad8b8bd10/output.json'] 2019-03-24 04:19:27,709 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a' Found ['/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a/43c8201ad8b8bd10'] 2019-03-24 04:19:27,709 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a/43c8201ad8b8bd10/output.json'] 2019-03-24 04:19:27,709 - 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/tmp6zFjcx.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/28_1T0wP7a -o /b/s/w/ir/tmp/t/tmpzAhGQf.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/tmp6zFjcx.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a', '-o', '/b/s/w/ir/tmp/t/tmpzAhGQf.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/tmp6zFjcx.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/28_1T0wP7a', '-o', '/b/s/w/ir/tmp/t/tmpzAhGQf.json'] returned exit code 0