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/swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmp2vc72H.json --task-output-dir /b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpO1oprymerge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmphRpQ17.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/tmpmp9f3F.json in dir /b/s/w/ir/kitchen-workdir: allow_subannotations: False base_name: 'ios_chrome_web_egtests (iPhone X iOS 11.4) on Mac-10.13.6' 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/swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmp2vc72H.json', '--task-output-dir', '/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpO1oprymerge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmphRpQ17.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/tmpmp9f3F.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name: 'ios_chrome_web_egtests (iPhone X iOS 11.4) on Mac-10.13.6' nest_level: 0 ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: Apple_PubSub_Socket_Render: /private/tmp/com.apple.launchd.kJ2W0LmJbT/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: 51859 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/8921479472293839760 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itBHg6vx/luci_context.559872433 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 PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SSH_AUTH_SOCK: /private/tmp/com.apple.launchd.prG1uA48wc/Listeners SWARMING_BOT_ID: vm149-m9 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43089b03d2bdc511 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-02-14 23:02:45,273 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz' 2019-02-14 23:02:45,273 - root: [WARNING] task_output_dir '/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz' already exists! 2019-02-14 23:02:45,273 - root: [WARNING] task_output_dir existing content: [] 2019-02-14 23:02:45,273 - 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/tmpmp9f3F.json -output-dir /b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz -task-summary-json /b/s/w/ir/tmp/t/tmphRpQ17.json 4308a9bca2af3710: exit 0 [I 2019-02-14 22:29:29] About to install Xcode 10b61 in Xcode.app for ios [10:29:30:INFO] Using Xcode version 10.1 build 10B61 at /b/s/w/ir/Xcode.app/Contents/Developer Device wiped. [10:29:39:DEBUG] Setting sigterm handler. [10:29:43:INFO] User defaults from command line: [10:29:43:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/7CC79784-3A2C-4E3E-8A08-8712F3F67B3B [10:29:43:INFO] [10:29:44:INFO] Testing started on 'iPhone X' [10:29:44:INFO] 2019-02-14 22:29:44.846 xcodebuild[63822:977174] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [10:29:44:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-fgkiqioueppgkhamqhqqymmdnhxj/Logs/Test/Test-Transient Testing-2019.02.14_22-29-44--0800.xcresult/1_Test/Diagnostics/ios_chrome_web_egtests_module-07EB40D4-BF1A-456A-B452-F341F427C78C/ios_chrome_web_egtests_module-5B394957-73A1-4365-821E-859124247803/Session-ios_chrome_web_egtests_module-2019-02-14_222944-jjG3X5.log [10:29:44:INFO] 2019-02-14 22:29:44.846 xcodebuild[63822:977165] [MT] IDETestOperationsObserverDebug: (3CF962BC-D3BA-494C-8EC8-9072C9E68F71) Beginning test session ios_chrome_web_egtests_module-3CF962BC-D3BA-494C-8EC8-9072C9E68F71 at 2019-02-14 22:29:44.846 with Xcode 10B61 on target <DVTiPhoneSimulator: 0x7fcd6529ab30> { [10:29:44:INFO] SimDevice: iPhone X (983C58EB-D73B-4842-8172-218A8D149C30, iOS 11.4, Shutdown) [10:29:44:INFO] } (11.4 (15F79)) [10:30:11:INFO] dyld: warning: could not load inserted library '/b/s/w/ir/Xcode.app/Contents/Developer/Platforms/iPhoneSimulator.platform/Developer/Library/PrivateFrameworks/IDEBundleInjection.framework/IDEBundleInjection' because image not found [10:30:12:INFO] 2019-02-14 22:30:12.362 ios_chrome_web_egtests[63948:978772] Crash handler setup started. [10:30:12:INFO] 2019-02-14 22:30:12.362 ios_chrome_web_egtests[63948:978772] Crash handler setup completed. [10:30:12:INFO] 2019-02-14 22:30:12.362 ios_chrome_web_egtests[63948:978772] Enabling accessibility for automation on Simulator. [10:30:12:INFO] objc[63948]: 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 (0x14bd854d0) 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 (0x14bb9be38). One of the two will be used. Which one is undefined. [10:30:16:INFO] 2019-02-14 22:30:16.616 ios_chrome_web_egtests[63948:978772] Unbalanced calls to begin/end appearance transitions for <TabGridViewController: 0x7f84e9063000>. [10:30:17:INFO] [0214/223017.311142:WARNING:leveldb_database.cc(111)] Unable to open /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/983C58EB-D73B-4842-8172-218A8D149C30/data/Containers/Data/Application/168B41CA-8067-4D74-AD5E-0E2BE3ADFF66/Library/Application Support/Chromium/Default/shared_proto_db: Invalid argument: /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/983C58EB-D73B-4842-8172-218A8D149C30/data/Containers/Data/Application/168B41CA-8067-4D74-AD5E-0E2BE3ADFF66/Library/Application Support/Chromium/Default/shared_proto_db: does not exist (create_if_missing is false) [10:30:24:INFO] Test Suite 'All tests' started at 2019-02-14 22:30:24.636 [10:30:24:INFO] Test Suite 'ios_chrome_web_egtests.app' started at 2019-02-14 22:30:24.637 [10:30:24:INFO] Test Suite 'BrowsingPreventDefaultTestCase' started at 2019-02-14 22:30:24.638 [10:30:24:INFO] [0214/223024.648376:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:30:26:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesStopPropagation]' started. [10:30:29:INFO] [0214/223029.020406:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:30:31:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesStopPropagation]' passed (5.420 seconds). [10:30:31:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesTargetBlank]' started. [10:30:37:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesTargetBlank]' passed (5.235 seconds). [10:30:37:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesWindowOpen]' started. [10:30:43:INFO] Test Case '-[BrowsingPreventDefaultTestCase testPreventDefaultOverridesWindowOpen]' passed (6.449 seconds). [10:30:43:INFO] Test Suite 'BrowsingPreventDefaultTestCase' passed at 2019-02-14 22:30:43.668. [10:30:43:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 17.104 (19.030) seconds [10:30:43:INFO] Test Suite 'BrowsingTestCase' started at 2019-02-14 22:30:43.669 [10:30:43:INFO] [0214/223043.670311:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:30:43:INFO] Test Case '-[BrowsingTestCase testBrowsingJavaScriptBasedNavigation]' started. [10:30:46:INFO] [0214/223046.054780:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:30:51:INFO] Test Case '-[BrowsingTestCase testBrowsingJavaScriptBasedNavigation]' passed (8.246 seconds). [10:30:51:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagation]' started. [10:30:58:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagation]' passed (6.410 seconds). [10:30:58:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagationRelativePath]' started. [10:31:04:INFO] Test Case '-[BrowsingTestCase testBrowsingStopPropagationRelativePath]' passed (6.547 seconds). [10:31:04:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromScript]' started. [10:31:07:INFO] 2019-02-14 22:31:07.057 ios_chrome_web_egtests[63948:978772] -- Earl Grey Test Skipped -- Tab Title not displayed on handset. [10:31:08:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromScript]' passed (4.108 seconds). [10:31:08:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromURL]' started. [10:31:11:INFO] 2019-02-14 22:31:11.214 ios_chrome_web_egtests[63948:978772] -- Earl Grey Test Skipped -- Tab Title not displayed on handset. [10:31:13:INFO] Test Case '-[BrowsingTestCase testBrowsingTabTitleSetFromURL]' passed (4.155 seconds). [10:31:13:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptNavigation]' started. [10:31:18:INFO] 2019-02-14 22:31:18.714 ios_chrome_web_egtests[63948:978772] Attempting to type key j. [10:31:18:INFO] 2019-02-14 22:31:18.719 ios_chrome_web_egtests[63948:978772] Tapping on key: j. [10:31:18:INFO] 2019-02-14 22:31:18.866 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:18:INFO] 2019-02-14 22:31:18.868 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:18:INFO] 2019-02-14 22:31:18.983 ios_chrome_web_egtests[63948:978772] Attempting to type key v. [10:31:18:INFO] 2019-02-14 22:31:18.985 ios_chrome_web_egtests[63948:978772] Tapping on key: v. [10:31:19:INFO] 2019-02-14 22:31:19.235 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:19:INFO] 2019-02-14 22:31:19.238 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:19:INFO] 2019-02-14 22:31:19.645 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:31:19:INFO] 2019-02-14 22:31:19.648 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:31:19:INFO] 2019-02-14 22:31:19.866 ios_chrome_web_egtests[63948:978772] Attempting to type key c. [10:31:19:INFO] 2019-02-14 22:31:19.868 ios_chrome_web_egtests[63948:978772] Tapping on key: c. [10:31:20:INFO] 2019-02-14 22:31:20.240 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:31:20:INFO] 2019-02-14 22:31:20.241 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:31:20:INFO] 2019-02-14 22:31:20.329 ios_chrome_web_egtests[63948:978772] Attempting to type key i. [10:31:20:INFO] 2019-02-14 22:31:20.330 ios_chrome_web_egtests[63948:978772] Tapping on key: i. [10:31:20:INFO] 2019-02-14 22:31:20.526 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:31:20:INFO] 2019-02-14 22:31:20.527 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:31:20:INFO] 2019-02-14 22:31:20.794 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:20:INFO] 2019-02-14 22:31:20.796 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:20:INFO] 2019-02-14 22:31:20.908 ios_chrome_web_egtests[63948:978772] Attempting to type key :. [10:31:21:INFO] 2019-02-14 22:31:21.125 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:21:INFO] 2019-02-14 22:31:21.229 ios_chrome_web_egtests[63948:978772] Tapping on key: :. [10:31:21:INFO] 2019-02-14 22:31:21.310 ios_chrome_web_egtests[63948:978772] Attempting to type key w. [10:31:21:INFO] 2019-02-14 22:31:21.619 ios_chrome_web_egtests[63948:978772] Tapping on key: more, letters. [10:31:21:INFO] 2019-02-14 22:31:21.672 ios_chrome_web_egtests[63948:978772] Tapping on key: w. [10:31:21:INFO] 2019-02-14 22:31:21.728 ios_chrome_web_egtests[63948:978772] Attempting to type key i. [10:31:21:INFO] 2019-02-14 22:31:21.729 ios_chrome_web_egtests[63948:978772] Tapping on key: i. [10:31:21:INFO] 2019-02-14 22:31:21.789 ios_chrome_web_egtests[63948:978772] Attempting to type key n. [10:31:21:INFO] 2019-02-14 22:31:21.792 ios_chrome_web_egtests[63948:978772] Tapping on key: n. [10:31:21:INFO] 2019-02-14 22:31:21.852 ios_chrome_web_egtests[63948:978772] Attempting to type key d. [10:31:21:INFO] 2019-02-14 22:31:21.853 ios_chrome_web_egtests[63948:978772] Tapping on key: d. [10:31:21:INFO] 2019-02-14 22:31:21.910 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:31:21:INFO] 2019-02-14 22:31:21.911 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:31:22:INFO] 2019-02-14 22:31:22.068 ios_chrome_web_egtests[63948:978772] Attempting to type key w. [10:31:22:INFO] 2019-02-14 22:31:22.069 ios_chrome_web_egtests[63948:978772] Tapping on key: w. [10:31:22:INFO] 2019-02-14 22:31:22.131 ios_chrome_web_egtests[63948:978772] Attempting to type key .. [10:31:22:INFO] 2019-02-14 22:31:22.133 ios_chrome_web_egtests[63948:978772] Tapping on key: .. [10:31:22:INFO] 2019-02-14 22:31:22.289 ios_chrome_web_egtests[63948:978772] Attempting to type key l. [10:31:22:INFO] 2019-02-14 22:31:22.290 ios_chrome_web_egtests[63948:978772] Tapping on key: l. [10:31:22:INFO] 2019-02-14 22:31:22.346 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:31:22:INFO] 2019-02-14 22:31:22.347 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:31:22:INFO] 2019-02-14 22:31:22.494 ios_chrome_web_egtests[63948:978772] Attempting to type key c. [10:31:22:INFO] 2019-02-14 22:31:22.495 ios_chrome_web_egtests[63948:978772] Tapping on key: c. [10:31:22:INFO] 2019-02-14 22:31:22.555 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:22:INFO] 2019-02-14 22:31:22.555 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:22:INFO] 2019-02-14 22:31:22.728 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:22:INFO] 2019-02-14 22:31:22.729 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:22:INFO] 2019-02-14 22:31:22.785 ios_chrome_web_egtests[63948:978772] Attempting to type key i. [10:31:22:INFO] 2019-02-14 22:31:22.785 ios_chrome_web_egtests[63948:978772] Tapping on key: i. [10:31:22:INFO] 2019-02-14 22:31:22.962 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:31:22:INFO] 2019-02-14 22:31:22.962 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:31:23:INFO] 2019-02-14 22:31:23.020 ios_chrome_web_egtests[63948:978772] Attempting to type key n. [10:31:23:INFO] 2019-02-14 22:31:23.021 ios_chrome_web_egtests[63948:978772] Tapping on key: n. [10:31:23:INFO] 2019-02-14 22:31:23.171 ios_chrome_web_egtests[63948:978772] Attempting to type key =. [10:31:23:INFO] 2019-02-14 22:31:23.276 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:23:INFO] 2019-02-14 22:31:23.431 ios_chrome_web_egtests[63948:978772] Tapping on key: more, symbols. [10:31:23:INFO] 2019-02-14 22:31:23.502 ios_chrome_web_egtests[63948:978772] Tapping on key: =. [10:31:23:INFO] 2019-02-14 22:31:23.563 ios_chrome_web_egtests[63948:978772] Attempting to type key '. [10:31:23:INFO] 2019-02-14 22:31:23.565 ios_chrome_web_egtests[63948:978772] Tapping on key: '. [10:31:23:INFO] 2019-02-14 22:31:23.655 ios_chrome_web_egtests[63948:978772] Attempting to type key h. [10:31:23:INFO] 2019-02-14 22:31:23.657 ios_chrome_web_egtests[63948:978772] Tapping on key: h. [10:31:23:INFO] 2019-02-14 22:31:23.715 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:23:INFO] 2019-02-14 22:31:23.715 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:23:INFO] 2019-02-14 22:31:23.837 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:23:INFO] 2019-02-14 22:31:23.838 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:23:INFO] 2019-02-14 22:31:23.905 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:31:23:INFO] 2019-02-14 22:31:23.906 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:31:24:INFO] 2019-02-14 22:31:24.079 ios_chrome_web_egtests[63948:978772] Attempting to type key :. [10:31:24:INFO] 2019-02-14 22:31:24.182 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:24:INFO] 2019-02-14 22:31:24.236 ios_chrome_web_egtests[63948:978772] Tapping on key: :. [10:31:24:INFO] 2019-02-14 22:31:24.300 ios_chrome_web_egtests[63948:978772] Attempting to type key /. [10:31:24:INFO] 2019-02-14 22:31:24.302 ios_chrome_web_egtests[63948:978772] Tapping on key: /. [10:31:24:INFO] 2019-02-14 22:31:24.366 ios_chrome_web_egtests[63948:978772] Attempting to type key /. [10:31:24:INFO] 2019-02-14 22:31:24.368 ios_chrome_web_egtests[63948:978772] Tapping on key: /. [10:31:24:INFO] 2019-02-14 22:31:24.426 ios_chrome_web_egtests[63948:978772] Attempting to type key 1. [10:31:24:INFO] 2019-02-14 22:31:24.427 ios_chrome_web_egtests[63948:978772] Tapping on key: 1. [10:31:24:INFO] 2019-02-14 22:31:24.509 ios_chrome_web_egtests[63948:978772] Attempting to type key 2. [10:31:24:INFO] 2019-02-14 22:31:24.510 ios_chrome_web_egtests[63948:978772] Tapping on key: 2. [10:31:24:INFO] 2019-02-14 22:31:24.569 ios_chrome_web_egtests[63948:978772] Attempting to type key 7. [10:31:24:INFO] 2019-02-14 22:31:24.570 ios_chrome_web_egtests[63948:978772] Tapping on key: 7. [10:31:24:INFO] 2019-02-14 22:31:24.757 ios_chrome_web_egtests[63948:978772] Attempting to type key .. [10:31:24:INFO] 2019-02-14 22:31:24.760 ios_chrome_web_egtests[63948:978772] Tapping on key: .. [10:31:24:INFO] 2019-02-14 22:31:24.819 ios_chrome_web_egtests[63948:978772] Attempting to type key 0. [10:31:24:INFO] 2019-02-14 22:31:24.820 ios_chrome_web_egtests[63948:978772] Tapping on key: 0. [10:31:24:INFO] 2019-02-14 22:31:24.981 ios_chrome_web_egtests[63948:978772] Attempting to type key .. [10:31:24:INFO] 2019-02-14 22:31:24.982 ios_chrome_web_egtests[63948:978772] Tapping on key: .. [10:31:25:INFO] 2019-02-14 22:31:25.043 ios_chrome_web_egtests[63948:978772] Attempting to type key 0. [10:31:25:INFO] 2019-02-14 22:31:25.045 ios_chrome_web_egtests[63948:978772] Tapping on key: 0. [10:31:25:INFO] 2019-02-14 22:31:25.168 ios_chrome_web_egtests[63948:978772] Attempting to type key .. [10:31:25:INFO] 2019-02-14 22:31:25.170 ios_chrome_web_egtests[63948:978772] Tapping on key: .. [10:31:25:INFO] 2019-02-14 22:31:25.232 ios_chrome_web_egtests[63948:978772] Attempting to type key 1. [10:31:25:INFO] 2019-02-14 22:31:25.232 ios_chrome_web_egtests[63948:978772] Tapping on key: 1. [10:31:25:INFO] 2019-02-14 22:31:25.348 ios_chrome_web_egtests[63948:978772] Attempting to type key :. [10:31:25:INFO] 2019-02-14 22:31:25.350 ios_chrome_web_egtests[63948:978772] Tapping on key: :. [10:31:25:INFO] 2019-02-14 22:31:25.408 ios_chrome_web_egtests[63948:978772] Attempting to type key 6. [10:31:25:INFO] 2019-02-14 22:31:25.410 ios_chrome_web_egtests[63948:978772] Tapping on key: 6. [10:31:25:INFO] 2019-02-14 22:31:25.535 ios_chrome_web_egtests[63948:978772] Attempting to type key 2. [10:31:25:INFO] 2019-02-14 22:31:25.535 ios_chrome_web_egtests[63948:978772] Tapping on key: 2. [10:31:25:INFO] 2019-02-14 22:31:25.605 ios_chrome_web_egtests[63948:978772] Attempting to type key 7. [10:31:25:INFO] 2019-02-14 22:31:25.607 ios_chrome_web_egtests[63948:978772] Tapping on key: 7. [10:31:25:INFO] 2019-02-14 22:31:25.725 ios_chrome_web_egtests[63948:978772] Attempting to type key 3. [10:31:25:INFO] 2019-02-14 22:31:25.726 ios_chrome_web_egtests[63948:978772] Tapping on key: 3. [10:31:25:INFO] 2019-02-14 22:31:25.786 ios_chrome_web_egtests[63948:978772] Attempting to type key 4. [10:31:25:INFO] 2019-02-14 22:31:25.787 ios_chrome_web_egtests[63948:978772] Tapping on key: 4. [10:31:25:INFO] 2019-02-14 22:31:25.921 ios_chrome_web_egtests[63948:978772] Attempting to type key /. [10:31:25:INFO] 2019-02-14 22:31:25.922 ios_chrome_web_egtests[63948:978772] Tapping on key: /. [10:31:25:INFO] 2019-02-14 22:31:25.982 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:26:INFO] 2019-02-14 22:31:26.289 ios_chrome_web_egtests[63948:978772] Tapping on key: more, letters. [10:31:26:INFO] 2019-02-14 22:31:26.343 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:26:INFO] 2019-02-14 22:31:26.405 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:26:INFO] 2019-02-14 22:31:26.406 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:26:INFO] 2019-02-14 22:31:26.465 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:31:26:INFO] 2019-02-14 22:31:26.466 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:31:26:INFO] 2019-02-14 22:31:26.536 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:31:26:INFO] 2019-02-14 22:31:26.537 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:31:26:INFO] 2019-02-14 22:31:26.598 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:31:26:INFO] 2019-02-14 22:31:26.599 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:31:26:INFO] 2019-02-14 22:31:26.662 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:26:INFO] 2019-02-14 22:31:26.663 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:26:INFO] 2019-02-14 22:31:26.791 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:31:26:INFO] 2019-02-14 22:31:26.792 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:31:26:INFO] 2019-02-14 22:31:26.852 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:26:INFO] 2019-02-14 22:31:26.853 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:27:INFO] 2019-02-14 22:31:27.009 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:31:27:INFO] 2019-02-14 22:31:27.010 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:31:27:INFO] 2019-02-14 22:31:27.072 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:31:27:INFO] 2019-02-14 22:31:27.073 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:31:27:INFO] 2019-02-14 22:31:27.206 ios_chrome_web_egtests[63948:978772] Attempting to type key /. [10:31:27:INFO] 2019-02-14 22:31:27.310 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:27:INFO] 2019-02-14 22:31:27.364 ios_chrome_web_egtests[63948:978772] Tapping on key: /. [10:31:27:INFO] 2019-02-14 22:31:27.442 ios_chrome_web_egtests[63948:978772] Attempting to type key '. [10:31:27:INFO] 2019-02-14 22:31:27.445 ios_chrome_web_egtests[63948:978772] Tapping on key: '. [10:31:32:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptNavigation]' passed (19.705 seconds). [10:31:32:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptWithoutNavigation]' started. [10:31:38:INFO] 2019-02-14 22:31:38.821 ios_chrome_web_egtests[63948:978772] Attempting to type key j. [10:31:38:INFO] 2019-02-14 22:31:38.822 ios_chrome_web_egtests[63948:978772] Tapping on key: j. [10:31:38:INFO] 2019-02-14 22:31:38.917 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:38:INFO] 2019-02-14 22:31:38.918 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:39:INFO] 2019-02-14 22:31:39.020 ios_chrome_web_egtests[63948:978772] Attempting to type key v. [10:31:39:INFO] 2019-02-14 22:31:39.022 ios_chrome_web_egtests[63948:978772] Tapping on key: v. [10:31:39:INFO] 2019-02-14 22:31:39.316 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:31:39:INFO] 2019-02-14 22:31:39.318 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:31:39:INFO] 2019-02-14 22:31:39.587 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:31:39:INFO] 2019-02-14 22:31:39.589 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:31:39:INFO] 2019-02-14 22:31:39.770 ios_chrome_web_egtests[63948:978772] Attempting to type key c. [10:31:39:INFO] 2019-02-14 22:31:39.771 ios_chrome_web_egtests[63948:978772] Tapping on key: c. [10:31:40:INFO] 2019-02-14 22:31:40.054 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:31:40:INFO] 2019-02-14 22:31:40.056 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:31:40:INFO] 2019-02-14 22:31:40.147 ios_chrome_web_egtests[63948:978772] Attempting to type key i. [10:31:40:INFO] 2019-02-14 22:31:40.147 ios_chrome_web_egtests[63948:978772] Tapping on key: i. [10:31:40:INFO] 2019-02-14 22:31:40.342 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:31:40:INFO] 2019-02-14 22:31:40.343 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:31:40:INFO] 2019-02-14 22:31:40.440 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:40:INFO] 2019-02-14 22:31:40.441 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:40:INFO] 2019-02-14 22:31:40.622 ios_chrome_web_egtests[63948:978772] Attempting to type key :. [10:31:40:INFO] 2019-02-14 22:31:40.752 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:40:INFO] 2019-02-14 22:31:40.807 ios_chrome_web_egtests[63948:978772] Tapping on key: :. [10:31:40:INFO] 2019-02-14 22:31:40.877 ios_chrome_web_egtests[63948:978772] Attempting to type key d. [10:31:41:INFO] 2019-02-14 22:31:41.186 ios_chrome_web_egtests[63948:978772] Tapping on key: more, letters. [10:31:41:INFO] 2019-02-14 22:31:41.240 ios_chrome_web_egtests[63948:978772] Tapping on key: d. [10:31:41:INFO] 2019-02-14 22:31:41.306 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:31:41:INFO] 2019-02-14 22:31:41.307 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:31:41:INFO] 2019-02-14 22:31:41.369 ios_chrome_web_egtests[63948:978772] Attempting to type key c. [10:31:41:INFO] 2019-02-14 22:31:41.370 ios_chrome_web_egtests[63948:978772] Tapping on key: c. [10:31:41:INFO] 2019-02-14 22:31:41.427 ios_chrome_web_egtests[63948:978772] Attempting to type key u. [10:31:41:INFO] 2019-02-14 22:31:41.428 ios_chrome_web_egtests[63948:978772] Tapping on key: u. [10:31:41:INFO] 2019-02-14 22:31:41.496 ios_chrome_web_egtests[63948:978772] Attempting to type key m. [10:31:41:INFO] 2019-02-14 22:31:41.497 ios_chrome_web_egtests[63948:978772] Tapping on key: m. [10:31:41:INFO] 2019-02-14 22:31:41.553 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:31:41:INFO] 2019-02-14 22:31:41.554 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:31:41:INFO] 2019-02-14 22:31:41.611 ios_chrome_web_egtests[63948:978772] Attempting to type key n. [10:31:41:INFO] 2019-02-14 22:31:41.612 ios_chrome_web_egtests[63948:978772] Tapping on key: n. [10:31:41:INFO] 2019-02-14 22:31:41.752 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:41:INFO] 2019-02-14 22:31:41.753 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:41:INFO] 2019-02-14 22:31:41.809 ios_chrome_web_egtests[63948:978772] Attempting to type key .. [10:31:41:INFO] 2019-02-14 22:31:41.811 ios_chrome_web_egtests[63948:978772] Tapping on key: .. [10:31:41:INFO] 2019-02-14 22:31:41.963 ios_chrome_web_egtests[63948:978772] Attempting to type key w. [10:31:41:INFO] 2019-02-14 22:31:41.964 ios_chrome_web_egtests[63948:978772] Tapping on key: w. [10:31:42:INFO] 2019-02-14 22:31:42.021 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:31:42:INFO] 2019-02-14 22:31:42.022 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:31:42:INFO] 2019-02-14 22:31:42.158 ios_chrome_web_egtests[63948:978772] Attempting to type key i. [10:31:42:INFO] 2019-02-14 22:31:42.159 ios_chrome_web_egtests[63948:978772] Tapping on key: i. [10:31:42:INFO] 2019-02-14 22:31:42.220 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:31:42:INFO] 2019-02-14 22:31:42.221 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:31:42:INFO] 2019-02-14 22:31:42.365 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:31:42:INFO] 2019-02-14 22:31:42.366 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:31:42:INFO] 2019-02-14 22:31:42.422 ios_chrome_web_egtests[63948:978772] Attempting to type key (. [10:31:42:INFO] 2019-02-14 22:31:42.525 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:42:INFO] 2019-02-14 22:31:42.580 ios_chrome_web_egtests[63948:978772] Tapping on key: (. [10:31:42:INFO] 2019-02-14 22:31:42.640 ios_chrome_web_egtests[63948:978772] Attempting to type key '. [10:31:42:INFO] 2019-02-14 22:31:42.643 ios_chrome_web_egtests[63948:978772] Tapping on key: '. [10:31:42:INFO] 2019-02-14 22:31:42.714 ios_chrome_web_egtests[63948:978772] Attempting to type key f. [10:31:42:INFO] 2019-02-14 22:31:42.716 ios_chrome_web_egtests[63948:978772] Tapping on key: f. [10:31:42:INFO] 2019-02-14 22:31:42.779 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:31:42:INFO] 2019-02-14 22:31:42.780 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:31:42:INFO] 2019-02-14 22:31:42.837 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:31:42:INFO] 2019-02-14 22:31:42.838 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:31:43:INFO] 2019-02-14 22:31:43.021 ios_chrome_web_egtests[63948:978772] Attempting to type key '. [10:31:43:INFO] 2019-02-14 22:31:43.125 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:43:INFO] 2019-02-14 22:31:43.181 ios_chrome_web_egtests[63948:978772] Tapping on key: '. [10:31:43:INFO] 2019-02-14 22:31:43.264 ios_chrome_web_egtests[63948:978772] Attempting to type key ). [10:31:43:INFO] 2019-02-14 22:31:43.367 ios_chrome_web_egtests[63948:978772] Tapping on key: more, numbers. [10:31:43:INFO] 2019-02-14 22:31:43.421 ios_chrome_web_egtests[63948:978772] Tapping on key: ). [10:31:43:INFO] 2019-02-14 22:31:43.488 ios_chrome_web_egtests[63948:978772] Attempting to type key [10:31:43:INFO] . [10:31:43:INFO] 2019-02-14 22:31:43.493 ios_chrome_web_egtests[63948:978772] Tapping on key: Go. [10:31:48:INFO] Test Case '-[BrowsingTestCase testBrowsingUserJavaScriptWithoutNavigation]' passed (15.556 seconds). [10:31:48:INFO] Test Case '-[BrowsingTestCase testBrowsingWindowDataLinkScriptRedirect]' started. [10:31:54:INFO] Test Case '-[BrowsingTestCase testBrowsingWindowDataLinkScriptRedirect]' passed (6.511 seconds). [10:31:54:INFO] Test Case '-[BrowsingTestCase testPDFLoadTitle]' started. [10:31:57:INFO] 2019-02-14 22:31:57.022 ios_chrome_web_egtests[63948:978772] -- Earl Grey Test Skipped -- Tab Title not displayed on handset. [10:31:58:INFO] Test Case '-[BrowsingTestCase testPDFLoadTitle]' passed (4.060 seconds). [10:31:58:INFO] Test Case '-[BrowsingTestCase testReload]' started. [10:32:08:INFO] Test Case '-[BrowsingTestCase testReload]' passed (9.841 seconds). [10:32:08:INFO] Test Case '-[BrowsingTestCase testTapLinkWithWebUIURL]' started. [10:32:14:INFO] Test Case '-[BrowsingTestCase testTapLinkWithWebUIURL]' passed (5.363 seconds). [10:32:14:INFO] Test Suite 'BrowsingTestCase' passed at 2019-02-14 22:32:14.184. [10:32:14:INFO] Executed 11 tests, with 0 failures (0 unexpected) in 90.501 (90.516) seconds [10:32:14:INFO] Test Suite 'CacheTestCase' started at 2019-02-14 22:32:14.185 [10:32:14:INFO] [0214/223214.187170:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:32:14:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnNavigateBackAndPageReload]' started. [10:32:16:INFO] 2019-02-14 22:32:16.449 ios_chrome_web_egtests[63948:978772] -- Earl Grey Test Disabled -- Disabled on iOS 10.3.1 and afterwards. [10:32:18:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnNavigateBackAndPageReload]' passed (4.202 seconds). [10:32:18:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnOpenNewTab]' started. [10:32:20:INFO] [0214/223220.802513:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:32:25:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnOpenNewTab]' passed (7.585 seconds). [10:32:25:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnSelectOmniboxSuggestion]' started. [10:32:31:INFO] 2019-02-14 22:32:31.286 ios_chrome_web_egtests[63948:978772] Attempting to type key c. [10:32:31:INFO] 2019-02-14 22:32:31.288 ios_chrome_web_egtests[63948:978772] Tapping on key: c. [10:32:31:INFO] 2019-02-14 22:32:31.375 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:32:31:INFO] 2019-02-14 22:32:31.376 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:32:31:INFO] 2019-02-14 22:32:31.505 ios_chrome_web_egtests[63948:978772] Attempting to type key c. [10:32:31:INFO] 2019-02-14 22:32:31.507 ios_chrome_web_egtests[63948:978772] Tapping on key: c. [10:32:31:INFO] 2019-02-14 22:32:31.618 ios_chrome_web_egtests[63948:978772] Attempting to type key h. [10:32:31:INFO] 2019-02-14 22:32:31.620 ios_chrome_web_egtests[63948:978772] Tapping on key: h. [10:32:31:INFO] 2019-02-14 22:32:31.890 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:32:31:INFO] 2019-02-14 22:32:31.914 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:32:32:INFO] 2019-02-14 22:32:32.291 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:32:32:INFO] 2019-02-14 22:32:32.293 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:32:32:INFO] 2019-02-14 22:32:32.391 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:32:32:INFO] 2019-02-14 22:32:32.392 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:32:32:INFO] 2019-02-14 22:32:32.587 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:32:32:INFO] 2019-02-14 22:32:32.588 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:32:32:INFO] 2019-02-14 22:32:32.723 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:32:32:INFO] 2019-02-14 22:32:32.724 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:32:32:INFO] 2019-02-14 22:32:32.889 ios_chrome_web_egtests[63948:978772] Attempting to type key f. [10:32:33:INFO] 2019-02-14 22:32:33.030 ios_chrome_web_egtests[63948:978772] Tapping on key: f. [10:32:33:INFO] 2019-02-14 22:32:33.120 ios_chrome_web_egtests[63948:978772] Attempting to type key i. [10:32:33:INFO] 2019-02-14 22:32:33.121 ios_chrome_web_egtests[63948:978772] Tapping on key: i. [10:32:33:INFO] 2019-02-14 22:32:33.230 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:32:33:INFO] 2019-02-14 22:32:33.231 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:32:33:INFO] 2019-02-14 22:32:33.403 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:32:33:INFO] 2019-02-14 22:32:33.404 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:32:33:INFO] 2019-02-14 22:32:33.640 ios_chrome_web_egtests[63948:978772] Attempting to type key t. [10:32:33:INFO] 2019-02-14 22:32:33.641 ios_chrome_web_egtests[63948:978772] Tapping on key: t. [10:32:33:INFO] 2019-02-14 22:32:33.859 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:32:33:INFO] 2019-02-14 22:32:33.860 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:32:34:INFO] 2019-02-14 22:32:34.082 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:32:34:INFO] 2019-02-14 22:32:34.086 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:32:34:INFO] 2019-02-14 22:32:34.150 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:32:34:INFO] 2019-02-14 22:32:34.151 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:32:34:INFO] 2019-02-14 22:32:34.321 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:32:34:INFO] 2019-02-14 22:32:34.321 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:32:38:INFO] Test Case '-[CacheTestCase testCachingBehaviorOnSelectOmniboxSuggestion]' passed (12.808 seconds). [10:32:38:INFO] Test Suite 'CacheTestCase' passed at 2019-02-14 22:32:38.787. [10:32:38:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 24.595 (24.602) seconds [10:32:38:INFO] Test Suite 'ChildWindowOpenByDOMTestCase' started at 2019-02-14 22:32:38.788 [10:32:38:INFO] [0214/223238.789948:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:32:38:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithName]' started. [10:32:41:INFO] [0214/223241.219907:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:32:45:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithName]' passed (6.765 seconds). [10:32:45:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithoutName]' started. [10:32:53:INFO] Test Case '-[ChildWindowOpenByDOMTestCase test2ChildWindowsWithoutName]' passed (8.149 seconds). [10:32:53:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testChildWindowsWithAndWithoutName]' started. [10:33:01:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testChildWindowsWithAndWithoutName]' passed (8.254 seconds). [10:33:01:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithName]' started. [10:33:09:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithName]' passed (7.486 seconds). [10:33:09:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithoutName]' started. [10:33:18:INFO] Test Case '-[ChildWindowOpenByDOMTestCase testWindowClosedWithoutName]' passed (9.251 seconds). [10:33:18:INFO] Test Suite 'ChildWindowOpenByDOMTestCase' passed at 2019-02-14 22:33:18.702. [10:33:18:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 39.905 (39.914) seconds [10:33:18:INFO] Test Suite 'ErrorPageTestCase' started at 2019-02-14 22:33:18.703 [10:33:18:INFO] [0214/223318.705105:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:33:18:INFO] Test Case '-[ErrorPageTestCase testReloadErrorPage]' started. [10:33:21:INFO] [0214/223321.275274:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:33:25:INFO] Test Case '-[ErrorPageTestCase testReloadErrorPage]' passed (6.756 seconds). [10:33:25:INFO] Test Case '-[ErrorPageTestCase testReloadPageAfterServerIsDown]' started. [10:33:27:INFO] [0214/223327.902781:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:33:32:INFO] Test Case '-[ErrorPageTestCase testReloadPageAfterServerIsDown]' passed (6.580 seconds). [10:33:32:INFO] Test Suite 'ErrorPageTestCase' passed at 2019-02-14 22:33:32.050. [10:33:32:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 13.336 (13.347) seconds [10:33:32:INFO] Test Suite 'FormsTestCase' started at 2019-02-14 22:33:32.051 [10:33:32:INFO] [0214/223332.053041:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:33:32:INFO] Test Case '-[FormsTestCase testGoBackButtonAfterFormSubmission]' started. [10:33:34:INFO] [0214/223334.591514:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:33:39:INFO] Test Case '-[FormsTestCase testGoBackButtonAfterFormSubmission]' passed (7.634 seconds). [10:33:39:INFO] Test Case '-[FormsTestCase testPostFormEntryWithKeyboard]' started. [10:33:52:INFO] Test Case '-[FormsTestCase testPostFormEntryWithKeyboard]' passed (12.683 seconds). [10:33:52:INFO] Test Case '-[FormsTestCase testPostFormToSamePage]' started. [10:34:02:INFO] Test Case '-[FormsTestCase testPostFormToSamePage]' passed (9.906 seconds). [10:34:02:INFO] Test Case '-[FormsTestCase testRepostFormAfterIndexNavigation]' started. [10:34:17:INFO] Test Case '-[FormsTestCase testRepostFormAfterIndexNavigation]' passed (15.339 seconds). [10:34:17:INFO] Test Case '-[FormsTestCase testRepostFormAfterReload]' started. [10:34:28:INFO] Test Case '-[FormsTestCase testRepostFormAfterReload]' passed (10.456 seconds). [10:34:28:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBack]' started. [10:34:39:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBack]' passed (11.402 seconds). [10:34:39:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBackAndForward]' started. [10:34:51:INFO] Test Case '-[FormsTestCase testRepostFormAfterTappingBackAndForward]' passed (11.618 seconds). [10:34:51:INFO] Test Case '-[FormsTestCase testRepostFormCancelling]' started. [10:35:01:INFO] Test Case '-[FormsTestCase testRepostFormCancelling]' passed (10.794 seconds). [10:35:01:INFO] Test Case '-[FormsTestCase testRepostFormCancellingAfterRedirect]' started. [10:35:09:INFO] Test Case '-[FormsTestCase testRepostFormCancellingAfterRedirect]' passed (7.699 seconds). [10:35:09:INFO] Test Case '-[FormsTestCase testRepostFormDismissedByNewNavigation]' started. [10:35:18:INFO] Test Case '-[FormsTestCase testRepostFormDismissedByNewNavigation]' passed (9.357 seconds). [10:35:18:INFO] Test Suite 'FormsTestCase' passed at 2019-02-14 22:35:18.952. [10:35:18:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 106.887 (106.901) seconds [10:35:18:INFO] Test Suite 'HTTPAuthTestCase' started at 2019-02-14 22:35:18.953 [10:35:18:INFO] [0214/223518.955383:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:35:18:INFO] Test Case '-[HTTPAuthTestCase testCancellingBasicAuth]' started. [10:35:25:INFO] Test Case '-[HTTPAuthTestCase testCancellingBasicAuth]' passed (6.714 seconds). [10:35:25:INFO] Test Case '-[HTTPAuthTestCase testSuccessfullBasicAuth]' started. [10:35:28:INFO] 2019-02-14 22:35:28.924 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:35:28:INFO] 2019-02-14 22:35:28.925 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:35:28:INFO] 2019-02-14 22:35:28.978 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:28:INFO] 2019-02-14 22:35:28.979 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:29:INFO] 2019-02-14 22:35:29.033 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:29:INFO] 2019-02-14 22:35:29.033 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:29:INFO] 2019-02-14 22:35:29.086 ios_chrome_web_egtests[63948:978772] Attempting to type key d. [10:35:29:INFO] 2019-02-14 22:35:29.087 ios_chrome_web_egtests[63948:978772] Tapping on key: d. [10:35:29:INFO] 2019-02-14 22:35:29.138 ios_chrome_web_egtests[63948:978772] Attempting to type key u. [10:35:29:INFO] 2019-02-14 22:35:29.139 ios_chrome_web_egtests[63948:978772] Tapping on key: u. [10:35:29:INFO] 2019-02-14 22:35:29.192 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:35:29:INFO] 2019-02-14 22:35:29.193 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:35:29:INFO] 2019-02-14 22:35:29.245 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:35:29:INFO] 2019-02-14 22:35:29.245 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:35:29:INFO] 2019-02-14 22:35:29.297 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:35:29:INFO] 2019-02-14 22:35:29.297 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:35:30:INFO] 2019-02-14 22:35:30.757 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:35:30:INFO] 2019-02-14 22:35:30.758 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:35:30:INFO] 2019-02-14 22:35:30.811 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:30:INFO] 2019-02-14 22:35:30.812 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:30:INFO] 2019-02-14 22:35:30.865 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:30:INFO] 2019-02-14 22:35:30.866 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:30:INFO] 2019-02-14 22:35:30.956 ios_chrome_web_egtests[63948:978772] Attempting to type key d. [10:35:30:INFO] 2019-02-14 22:35:30.957 ios_chrome_web_egtests[63948:978772] Tapping on key: d. [10:35:31:INFO] 2019-02-14 22:35:31.010 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:35:31:INFO] 2019-02-14 22:35:31.011 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:35:31:INFO] 2019-02-14 22:35:31.064 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:35:31:INFO] 2019-02-14 22:35:31.065 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:35:31:INFO] 2019-02-14 22:35:31.118 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:35:31:INFO] 2019-02-14 22:35:31.119 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:35:31:INFO] 2019-02-14 22:35:31.172 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:35:31:INFO] 2019-02-14 22:35:31.173 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:35:32:INFO] [0214/223532.974315:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:35:35:INFO] Test Case '-[HTTPAuthTestCase testSuccessfullBasicAuth]' passed (10.282 seconds). [10:35:35:INFO] Test Case '-[HTTPAuthTestCase testUnsuccessfullBasicAuth]' started. [10:35:39:INFO] 2019-02-14 22:35:39.225 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:35:39:INFO] 2019-02-14 22:35:39.226 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:35:39:INFO] 2019-02-14 22:35:39.279 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:39:INFO] 2019-02-14 22:35:39.280 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:39:INFO] 2019-02-14 22:35:39.332 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:39:INFO] 2019-02-14 22:35:39.332 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:39:INFO] 2019-02-14 22:35:39.384 ios_chrome_web_egtests[63948:978772] Attempting to type key d. [10:35:39:INFO] 2019-02-14 22:35:39.385 ios_chrome_web_egtests[63948:978772] Tapping on key: d. [10:35:39:INFO] 2019-02-14 22:35:39.437 ios_chrome_web_egtests[63948:978772] Attempting to type key u. [10:35:39:INFO] 2019-02-14 22:35:39.438 ios_chrome_web_egtests[63948:978772] Tapping on key: u. [10:35:39:INFO] 2019-02-14 22:35:39.491 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:35:39:INFO] 2019-02-14 22:35:39.492 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:35:39:INFO] 2019-02-14 22:35:39.545 ios_chrome_web_egtests[63948:978772] Attempting to type key e. [10:35:39:INFO] 2019-02-14 22:35:39.545 ios_chrome_web_egtests[63948:978772] Tapping on key: e. [10:35:39:INFO] 2019-02-14 22:35:39.598 ios_chrome_web_egtests[63948:978772] Attempting to type key r. [10:35:39:INFO] 2019-02-14 22:35:39.599 ios_chrome_web_egtests[63948:978772] Tapping on key: r. [10:35:40:INFO] 2019-02-14 22:35:40.968 ios_chrome_web_egtests[63948:978772] Attempting to type key g. [10:35:40:INFO] 2019-02-14 22:35:40.969 ios_chrome_web_egtests[63948:978772] Tapping on key: g. [10:35:41:INFO] 2019-02-14 22:35:41.022 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:41:INFO] 2019-02-14 22:35:41.023 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:41:INFO] 2019-02-14 22:35:41.076 ios_chrome_web_egtests[63948:978772] Attempting to type key o. [10:35:41:INFO] 2019-02-14 22:35:41.076 ios_chrome_web_egtests[63948:978772] Tapping on key: o. [10:35:41:INFO] 2019-02-14 22:35:41.154 ios_chrome_web_egtests[63948:978772] Attempting to type key d. [10:35:41:INFO] 2019-02-14 22:35:41.154 ios_chrome_web_egtests[63948:978772] Tapping on key: d. [10:35:41:INFO] 2019-02-14 22:35:41.207 ios_chrome_web_egtests[63948:978772] Attempting to type key p. [10:35:41:INFO] 2019-02-14 22:35:41.208 ios_chrome_web_egtests[63948:978772] Tapping on key: p. [10:35:41:INFO] 2019-02-14 22:35:41.260 ios_chrome_web_egtests[63948:978772] Attempting to type key a. [10:35:41:INFO] 2019-02-14 22:35:41.261 ios_chrome_web_egtests[63948:978772] Tapping on key: a. [10:35:41:INFO] 2019-02-14 22:35:41.314 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:35:41:INFO] 2019-02-14 22:35:41.315 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:35:41:INFO] 2019-02-14 22:35:41.368 ios_chrome_web_egtests[63948:978772] Attempting to type key s. [10:35:41:INFO] 2019-02-14 22:35:41.369 ios_chrome_web_egtests[63948:978772] Tapping on key: s. [10:35:47:INFO] Test Case '-[HTTPAuthTestCase testUnsuccessfullBasicAuth]' passed (11.377 seconds). [10:35:47:INFO] Test Suite 'HTTPAuthTestCase' passed at 2019-02-14 22:35:47.333. [10:35:47:INFO] Executed 3 tests, with 0 failures (0 unexpected) in 28.372 (28.380) seconds [10:35:47:INFO] Test Suite 'JSPrintTestCase' started at 2019-02-14 22:35:47.334 [10:35:47:INFO] [0214/223547.335499:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:35:47:INFO] Test Case '-[JSPrintTestCase testWebPrintButton]' started. [10:35:49:INFO] [0214/223549.715586:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:35:51:INFO] 2019-02-14 22:35:51.338 ios_chrome_web_egtests[63948:978772] Unbalanced calls to begin/end appearance transitions for <UIViewController: 0x7f84f16144d0>. [10:35:54:INFO] 2019-02-14 22:35:54.454 ios_chrome_web_egtests[63948:978772] Warning: Attempt to dismiss from view controller <UIViewController: 0x7f84f16144d0> while a presentation or dismiss is in progress! [10:35:56:INFO] Test Case '-[JSPrintTestCase testWebPrintButton]' passed (9.516 seconds). [10:35:56:INFO] Test Suite 'JSPrintTestCase' passed at 2019-02-14 22:35:56.861. [10:35:56:INFO] Executed 1 test, with 0 failures (0 unexpected) in 9.516 (9.527) seconds [10:35:56:INFO] Test Suite 'NavigationTestCase' started at 2019-02-14 22:35:56.861 [10:35:56:INFO] [0214/223556.863312:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:35:56:INFO] Test Case '-[NavigationTestCase test301Redirect]' started. [10:35:59:INFO] [0214/223559.302770:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:36:09:INFO] Test Case '-[NavigationTestCase test301Redirect]' passed (12.890 seconds). [10:36:09:INFO] Test Case '-[NavigationTestCase testHistoryBackAndForwardAroundNTP]' started. [10:36:12:INFO] [0214/223612.262819:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:36:19:INFO] Test Case '-[NavigationTestCase testHistoryBackAndForwardAroundNTP]' passed (10.236 seconds). [10:36:19:INFO] Test Case '-[NavigationTestCase testHistoryBackNavigation]' started. [10:36:22:INFO] [0214/223622.433481:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:36:29:INFO] Test Case '-[NavigationTestCase testHistoryBackNavigation]' passed (9.081 seconds). [10:36:29:INFO] Test Case '-[NavigationTestCase testHistoryCrossDocumentLoad]' started. [10:36:31:INFO] [0214/223631.595755:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:36:37:INFO] Test Case '-[NavigationTestCase testHistoryCrossDocumentLoad]' passed (8.703 seconds). [10:36:37:INFO] Test Case '-[NavigationTestCase testHistoryForwardNavigation]' started. [10:36:40:INFO] [0214/223640.127080:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:36:46:INFO] Test Case '-[NavigationTestCase testHistoryForwardNavigation]' passed (9.165 seconds). [10:36:46:INFO] Test Case '-[NavigationTestCase testHistoryGoDelta]' started. [10:36:49:INFO] [0214/223649.447660:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:36:57:INFO] Test Case '-[NavigationTestCase testHistoryGoDelta]' passed (10.851 seconds). [10:36:57:INFO] Test Case '-[NavigationTestCase testHistoryGoDeltaZero]' started. [10:37:00:INFO] [0214/223700.215960:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:37:04:INFO] Test Case '-[NavigationTestCase testHistoryGoDeltaZero]' passed (6.444 seconds). [10:37:04:INFO] Test Case '-[NavigationTestCase testHistoryGoNoParameter]' started. [10:37:06:INFO] [0214/223706.600065:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:37:10:INFO] Test Case '-[NavigationTestCase testHistoryGoNoParameter]' passed (6.386 seconds). [10:37:10:INFO] Test Case '-[NavigationTestCase testHistoryGoOutOfBounds]' started. [10:37:13:INFO] [0214/223713.067220:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:37:18:INFO] Test Case '-[NavigationTestCase testHistoryGoOutOfBounds]' passed (7.438 seconds). [10:37:18:INFO] Test Case '-[NavigationTestCase testJavaScriptRedirect]' started. [10:37:20:INFO] [0214/223720.499956:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:37:27:INFO] Test Case '-[NavigationTestCase testJavaScriptRedirect]' passed (9.636 seconds). [10:37:27:INFO] Test Case '-[NavigationTestCase testRedirectRefresh]' started. [10:37:30:INFO] [0214/223730.150038:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:37:40:INFO] Test Case '-[NavigationTestCase testRedirectRefresh]' passed (12.623 seconds). [10:37:40:INFO] Test Case '-[NavigationTestCase testRedirectWindow]' started. [10:37:42:INFO] [0214/223742.747028:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:37:53:INFO] Test Case '-[NavigationTestCase testRedirectWindow]' passed (12.801 seconds). [10:37:53:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToNTPAndNavigateForward]' started. [10:37:55:INFO] [0214/223755.439923:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:38:01:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToNTPAndNavigateForward]' passed (8.413 seconds). [10:38:01:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToWebUIAndNavigateForward]' started. [10:38:05:INFO] [0214/223805.046103:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:38:07:INFO] [0214/223807.123135:ERROR:crw_web_controller.mm(2415)] WKWebView snapshot error: 1 [10:38:13:INFO] Test Case '-[NavigationTestCase testRestoreHistoryToWebUIAndNavigateForward]' passed (11.497 seconds). [10:38:13:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeHash]' started. [10:38:15:INFO] [0214/223815.429306:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:38:23:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeHash]' passed (10.467 seconds). [10:38:23:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeToSameHash]' started. [10:38:25:INFO] [0214/223825.894678:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:38:33:INFO] Test Case '-[NavigationTestCase testWindowLocationChangeToSameHash]' passed (9.589 seconds). [10:38:33:INFO] Test Case '-[NavigationTestCase testWindowLocationReplaceAndChangeHash]' started. [10:38:35:INFO] [0214/223835.414907:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:38:41:INFO] Test Case '-[NavigationTestCase testWindowLocationReplaceAndChangeHash]' passed (8.831 seconds). [10:38:41:INFO] Test Suite 'NavigationTestCase' passed at 2019-02-14 22:38:41.932. [10:38:41:INFO] Executed 17 tests, with 0 failures (0 unexpected) in 165.050 (165.071) seconds [10:38:41:INFO] Test Suite 'ProgressIndicatorTestCase' started at 2019-02-14 22:38:41.933 [10:38:41:INFO] [0214/223841.935299:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:38:41:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterFormSubmit]' started. [10:38:44:INFO] [0214/223844.425894:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:38:48:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterFormSubmit]' passed (6.501 seconds). [10:38:48:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterSuppressedFormPost]' started. [10:38:54:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorDisappearsAfterSuppressedFormPost]' passed (6.209 seconds). [10:38:54:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShown]' started. [10:39:02:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShown]' passed (7.505 seconds). [10:39:02:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShownOnFormSubmit]' started. [10:39:10:INFO] Test Case '-[ProgressIndicatorTestCase testProgressIndicatorShownOnFormSubmit]' passed (8.600 seconds). [10:39:10:INFO] Test Suite 'ProgressIndicatorTestCase' passed at 2019-02-14 22:39:10.758. [10:39:10:INFO] Executed 4 tests, with 0 failures (0 unexpected) in 28.816 (28.824) seconds [10:39:10:INFO] Test Suite 'PushAndReplaceStateNavigationTestCase' started at 2019-02-14 22:39:10.759 [10:39:10:INFO] [0214/223910.760347:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:39:10:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryNavigatingPastNonPushedURL]' started. [10:39:13:INFO] [0214/223913.200757:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:39:27:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryNavigatingPastNonPushedURL]' passed (16.523 seconds). [10:39:27:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushStateThenGoBackAndForward]' started. [10:39:38:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushStateThenGoBackAndForward]' passed (11.400 seconds). [10:39:38:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushUnicodeCharacters]' started. [10:39:48:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryPushUnicodeCharacters]' passed (9.502 seconds). [10:39:48:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryReplaceStateThenGoBackAndForward]' started. [10:40:03:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryReplaceStateThenGoBackAndForward]' passed (15.314 seconds). [10:40:03:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryWithBase]' started. [10:40:08:INFO] Test Case '-[PushAndReplaceStateNavigationTestCase testHtml5HistoryWithBase]' passed (5.344 seconds). [10:40:08:INFO] Test Suite 'PushAndReplaceStateNavigationTestCase' passed at 2019-02-14 22:40:08.850. [10:40:08:INFO] Executed 5 tests, with 0 failures (0 unexpected) in 58.085 (58.091) seconds [10:40:08:INFO] Test Suite 'StopLoadingTestCase' started at 2019-02-14 22:40:08.850 [10:40:08:INFO] [0214/224008.851762:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:40:08:INFO] Test Case '-[StopLoadingTestCase testStopLoading]' started. [10:40:22:INFO] Test Case '-[StopLoadingTestCase testStopLoading]' passed (13.424 seconds). [10:40:22:INFO] Test Suite 'StopLoadingTestCase' passed at 2019-02-14 22:40:22.278. [10:40:22:INFO] Executed 1 test, with 0 failures (0 unexpected) in 13.424 (13.428) seconds [10:40:22:INFO] Test Suite 'TabOrderTestCase' started at 2019-02-14 22:40:22.279 [10:40:22:INFO] [0214/224022.280330:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:40:22:INFO] Test Case '-[TabOrderTestCase testChildTabOrdering]' started. [10:40:24:INFO] [0214/224024.709434:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:40:49:INFO] Test Case '-[TabOrderTestCase testChildTabOrdering]' passed (27.533 seconds). [10:40:49:INFO] Test Suite 'TabOrderTestCase' passed at 2019-02-14 22:40:49.820. [10:40:49:INFO] Executed 1 test, with 0 failures (0 unexpected) in 27.533 (27.541) seconds [10:40:49:INFO] Test Suite 'VisibleURLTestCase' started at 2019-02-14 22:40:49.820 [10:40:49:INFO] [0214/224049.821741:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:40:49:INFO] Test Case '-[VisibleURLTestCase testBackForwardNavigation]' started. [10:40:52:INFO] [0214/224052.189113:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:41:03:INFO] Test Case '-[VisibleURLTestCase testBackForwardNavigation]' passed (13.350 seconds). [10:41:03:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingReload]' started. [10:41:17:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingReload]' passed (14.683 seconds). [10:41:17:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingRendererInitiatedNavigation]' started. [10:41:27:INFO] Test Case '-[VisibleURLTestCase testBackNavigationWithPendingRendererInitiatedNavigation]' passed (9.450 seconds). [10:41:27:INFO] Test Case '-[VisibleURLTestCase testDoubleBackJSNavigation]' started. [10:41:37:INFO] Test Case '-[VisibleURLTestCase testDoubleBackJSNavigation]' passed (10.495 seconds). [10:41:37:INFO] Test Case '-[VisibleURLTestCase testDoubleBackNavigation]' started. [10:41:43:INFO] [0214/224143.147389:ERROR:crw_web_controller.mm(2415)] WKWebView snapshot error: 1 [10:41:49:INFO] Test Case '-[VisibleURLTestCase testDoubleBackNavigation]' passed (11.930 seconds). [10:41:49:INFO] Test Case '-[VisibleURLTestCase testDoubleForwardNavigationToWebUIPage]' started. [10:42:01:INFO] Test Case '-[VisibleURLTestCase testDoubleForwardNavigationToWebUIPage]' passed (11.814 seconds). [10:42:01:INFO] Test Case '-[VisibleURLTestCase testHistoryNavigation]' started. [10:42:05:INFO] [0214/224205.882623:ERROR:crw_web_controller.mm(2415)] WKWebView snapshot error: 1 [10:42:13:INFO] Test Case '-[VisibleURLTestCase testHistoryNavigation]' passed (11.972 seconds). [10:42:13:INFO] Test Case '-[VisibleURLTestCase testJSBackForwardNavigation]' started. [10:42:26:INFO] Test Case '-[VisibleURLTestCase testJSBackForwardNavigation]' passed (12.612 seconds). [10:42:26:INFO] Test Case '-[VisibleURLTestCase testJSGoNavigation]' started. [10:42:30:INFO] [0214/224230.451395:ERROR:crw_web_controller.mm(2415)] WKWebView snapshot error: 1 [10:42:38:INFO] Test Case '-[VisibleURLTestCase testJSGoNavigation]' passed (12.756 seconds). [10:42:38:INFO] Test Case '-[VisibleURLTestCase testRendererInitiatedNavigationWithPendingBackNavigation]' started. [10:42:48:INFO] Test Case '-[VisibleURLTestCase testRendererInitiatedNavigationWithPendingBackNavigation]' passed (9.508 seconds). [10:42:48:INFO] Test Case '-[VisibleURLTestCase testStoppingPendingBackNavigationAndReload]' started. [10:43:06:INFO] Test Case '-[VisibleURLTestCase testStoppingPendingBackNavigationAndReload]' passed (18.020 seconds). [10:43:06:INFO] Test Suite 'VisibleURLTestCase' passed at 2019-02-14 22:43:06.424. [10:43:06:INFO] Executed 11 tests, with 0 failures (0 unexpected) in 136.590 (136.604) seconds [10:43:06:INFO] Test Suite 'WindowOpenByDOMTestCase' started at 2019-02-14 22:43:06.425 [10:43:06:INFO] [0214/224306.426490:WARNING:loopback_server.cc(723)] Loopback sync persistent state file does not exist. [10:43:06:INFO] Test Case '-[WindowOpenByDOMTestCase testBlockPopupInjectedIntoOpenedWindow]' started. [10:43:08:INFO] [0214/224308.896964:WARNING:embedded_test_server.cc(237)] Request not handled. Returning 404: /favicon.ico [10:43:13:INFO] Test Case '-[WindowOpenByDOMTestCase testBlockPopupInjectedIntoOpenedWindow]' passed (6.718 seconds). [10:43:13:INFO] Test Case '-[WindowOpenByDOMTestCase testCloseWindowNotOpenByDOM]' started. [10:43:18:INFO] Test Case '-[WindowOpenByDOMTestCase testCloseWindowNotOpenByDOM]' passed (5.291 seconds). [10:43:18:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTarget]' started. [10:43:26:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTarget]' passed (7.659 seconds). [10:43:26:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetMultipleTimes]' started. [10:43:37:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetMultipleTimes]' passed (11.847 seconds). [10:43:37:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetSessionStorage]' started. [10:43:45:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetSessionStorage]' passed (7.390 seconds). [10:43:45:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithDelayedClose]' started. [10:43:54:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithDelayedClose]' passed (9.061 seconds). [10:43:54:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithImmediateClose]' started. [10:44:00:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithImmediateClose]' passed (6.485 seconds). [10:44:00:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithoutUserGesture]' started. [10:44:07:INFO] Test Case '-[WindowOpenByDOMTestCase testLinkWithBlankTargetWithoutUserGesture]' passed (6.135 seconds). [10:44:07:INFO] Test Case '-[WindowOpenByDOMTestCase testLocationReplaceInWindowOpenWithEmptyTarget]' started. [10:44:13:INFO] Test Case '-[WindowOpenByDOMTestCase testLocationReplaceInWindowOpenWithEmptyTarget]' passed (6.396 seconds). [10:44:13:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndAssignToHref]' started. [10:44:16:INFO] [0214/224416.778145:WARNING:crw_web_controller.mm(2614)] Message from JS ignored due to non-matching windowID: 6347E70B506C2DB283FCA04313070F20 != DF82E81D059A8BDCC9B5058BDE937EBC [10:44:16:INFO] [0214/224416.778314:WARNING:crw_web_controller.mm(2564)] Message from JS not handled due to invalid format [10:44:19:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndAssignToHref]' passed (6.402 seconds). [10:44:19:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndCallLocationAssign]' started. [10:44:26:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndCallLocationAssign]' passed (6.479 seconds). [10:44:26:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndSetLocation]' started. [10:44:32:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenAndSetLocation]' passed (6.560 seconds). [10:44:32:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithBlankTarget]' started. [10:44:39:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithBlankTarget]' passed (6.467 seconds). [10:44:39:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithButtonOnClick]' started. [10:44:45:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithButtonOnClick]' passed (6.449 seconds). [10:44:45:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithEmptyTarget]' started. [10:44:52:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithEmptyTarget]' passed (6.482 seconds). [10:44:52:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithMetaRefresh]' started. [10:44:55:INFO] [0214/224455.783464:WARNING:crw_web_controller.mm(2614)] Message from JS ignored due to non-matching windowID: D62DB91041305651896333973B2AEC1E != F93758C8BC37694D428679D46D79701D [10:44:55:INFO] [0214/224455.783637:WARNING:crw_web_controller.mm(2564)] Message from JS not handled due to invalid format [10:44:58:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithMetaRefresh]' passed (6.538 seconds). [10:44:58:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithPreventDefaultLink]' started. [10:45:05:INFO] Test Case '-[WindowOpenByDOMTestCase testWindowOpenWithPreventDefaultLink]' passed (6.954 seconds). [10:45:05:INFO] Test Suite 'WindowOpenByDOMTestCase' passed at 2019-02-14 22:45:05.755. [10:45:05:INFO] Executed 17 tests, with 0 failures (0 unexpected) in 119.312 (119.330) seconds [10:45:05:INFO] Test Suite 'ios_chrome_web_egtests.app' passed at 2019-02-14 22:45:05.756. [10:45:05:INFO] Executed 94 tests, with 0 failures (0 unexpected) in 879.026 (881.119) seconds [10:45:05:INFO] Test Suite 'All tests' passed at 2019-02-14 22:45:05.757. [10:45:05:INFO] Executed 94 tests, with 0 failures (0 unexpected) in 879.026 (881.121) seconds [10:45:06:INFO] [10:45:06:INFO] [10:45:06:INFO] Test session results and logs: [10:45:06:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-fgkiqioueppgkhamqhqqymmdnhxj/Logs/Test/Test-Transient Testing-2019.02.14_22-29-44--0800.xcresult [10:45:06:INFO] [10:45:06:INFO] 2019-02-14 22:45:06.086 xcodebuild[63822:977165] [MT] IDETestOperationsObserverDebug: 921.663 elapsed -- Testing started completed. [10:45:06:INFO] 2019-02-14 22:45:06.086 xcodebuild[63822:977165] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [10:45:06:INFO] 2019-02-14 22:45:06.086 xcodebuild[63822:977165] [MT] IDETestOperationsObserverDebug: 921.663 sec, +921.663 sec -- end [10:45:06:INFO] ** TEST EXECUTE SUCCEEDED ** [10:45:06:INFO] [10:45:10:INFO] Waiting for test process to terminate. [10:45:10:INFO] Test process terminated. [10:45:10:DEBUG] Setting sigterm handler. [10:45:10:DEBUG] Stdout flushed after test process. [10:45:10:DEBUG] Processing test results. [10:45:10:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0 [10:45:10:DEBUG] Extracting test data. [10:45:10:DEBUG] Retrieving crash reports. [10:45:10:DEBUG] Retrieving derived data. [10:45:10:DEBUG] Making desktop screenshots. [10:45:10:DEBUG] Killing simulators. [10:45:15:DEBUG] Wiping simulator. Device wiped. [10:45:18:DEBUG] End of tear_down. 2019-02-14 23:02:46,238 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz/4308a9bca2af3710'] 2019-02-14 23:02:46,238 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz/4308a9bca2af3710/output.json']) Found: [] Expected: ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz/4308a9bca2af3710/output.json'] 2019-02-14 23:02:46,239 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz' Found ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz/4308a9bca2af3710'] 2019-02-14 23:02:46,239 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz/4308a9bca2af3710/output.json'] 2019-02-14 23:02:46,239 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmphRpQ17.json --task-output-dir /b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz -o /b/s/w/ir/tmp/t/tmp2vc72H.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmphRpQ17.json', '--task-output-dir', '/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz', '-o', '/b/s/w/ir/tmp/t/tmp2vc72H.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/swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmphRpQ17.json', '--task-output-dir', '/b/s/w/ir/kitchen-workdir/recipe_cleanup/35OF35cz', '-o', '/b/s/w/ir/tmp/t/tmp2vc72H.json'] returned exit code 0