action #124161
Updated by nanzhang about 1 year ago
## Observation Frequently establishing websocket connection will cause it hard to match the next needle, as the sending key action was lost. Refer to the following job links. https://openqa.suse.de/tests/10443972/logfile?filename=autoinst-log.txt (After -- After sending key 'ret', the websocket connection was closed which cause the key was actually not received by installer.) installer. ~~~ [2023-02-07T16:06:34.061841+01:00] [debug] [pid:11593] tests/migration/online_migration/online_migration_setup.pm:26 called opensusebasetest::wait_boot -> lib/opensusebasetest.pm:919 called opensusebasetest::wait_boot_past_bootloader -> lib/opensusebasetest.pm:798 called opensusebasetest::handle_displaymanager_login -> lib/opensusebasetest.pm:600 called x11utils::handle_login -> lib/x11utils.pm:287 called x11utils::select_user_gnome -> lib/x11utils.pm:361 called testapi::send_key [2023-02-07T16:06:34.062001+01:00] [debug] [pid:11593] <<< testapi::send_key(key="ret", do_wait=0, wait_screen_change=0) [2023-02-07T16:06:34.130287+01:00] [debug] [pid:11594] considering VNC stalled, no update for 5.42 seconds [2023-02-07 16:06:34.13047] [18272] [info] Client closed connection ~~~ https://openqa.suse.de/tests/10448675/logfile?filename=autoinst-log.txt (After -- After sending key 'alt-n', the websocket connection was closed.) closed. ~~~ [2023-02-08T04:14:50.502113+01:00] [debug] [pid:12018] tests/installation/scc_registration.pm:25 called registration::fill_in_registration_data -> lib/registration.pm:605 called registration::process_modules -> lib/registration.pm:1010 called registration::process_scc_register_addons -> lib/registration.pm:504 called testapi::wait_screen_change -> lib/registration.pm:504 called testapi::send_key [2023-02-08T04:14:50.502296+01:00] [debug] [pid:12018] <<< testapi::send_key(key="alt-n", do_wait=0, wait_screen_change=0) [2023-02-08T04:14:50.684616+01:00] [debug] [pid:12019] considering VNC stalled, no update for 1675826090.68 seconds [2023-02-08 04:14:50.68489] [14326] [info] Client closed connection ~~~ http://10.67.129.96/tests/1848/logfile?filename=autoinst-log.txt (Before -- Before sending key 'alt-y', the websocket connection was closed.) closed. ~~~ [2023-02-08T17:35:48.410521+08:00] [warn] [pid:9161] !!! backend::baseclass::check_asserted_screen: check_asserted_screen took 4.30 seconds for 137 candidate needles - make your needles more specific [2023-02-08T17:35:48.410756+08:00] [debug] [pid:9161] no match: 359.9s, best candidate: registration-refreshing-repository-text-20210317 (0.29) [2023-02-08 17:36:03.42835] [9750] [info] Client closed connection [2023-02-08 17:36:03.43039] [9750] [info] WebSocket closed with status 1000. [2023-02-08T17:36:08.476712+08:00] [debug] [pid:9160] >>> testapi::_handle_found_needle: found registration-online-repos-15sp1-20181217, similarity 1.00 @ 258/336 [2023-02-08T17:36:08.478434+08:00] [debug] [pid:9160] tests/installation/scc_registration.pm:25 called registration::fill_in_registration_data -> lib/registration.pm:596 called registration::handle_scc_popups -> lib/registration.pm:663 called testapi::wait_screen_change [2023-02-08T17:36:08.478679+08:00] [debug] [pid:9160] <<< testapi::wait_screen_change(timeout=10, similarity_level=50) [2023-02-08T17:36:08.480838+08:00] [debug] [pid:9160] tests/installation/scc_registration.pm:25 called registration::fill_in_registration_data -> lib/registration.pm:596 called registration::handle_scc_popups -> lib/registration.pm:663 called testapi::wait_screen_change -> lib/registration.pm:663 called testapi::send_key [2023-02-08T17:36:08.481091+08:00] [debug] [pid:9160] <<< testapi::send_key(key="alt-y", do_wait=0, wait_screen_change=0) [2023-02-08T17:36:08.629838+08:00] [debug] [pid:9161] considering VNC stalled, no update for 20.22 seconds [2023-02-08T17:36:08.631376+08:00] [debug] [pid:9161] Establishing VNC connection over WebSockets via https://10.67.131.2 [2023-02-08 17:36:09.69433] [9809] [info] Establishing WebSocket connection to wss://vh002.qa2.suse.asia:443/ticket/227709543f641d16 [2023-02-08 17:36:09.69545] [9809] [info] Client accepted [2023-02-08 17:36:09.70746] [9809] [info] WebSocket connection established ~~~ ## Steps to reproduce Trigger below test runs in OSD: https://openqa.suse.de/tests/overview?distri=sle&version=15-SP5&build=72.4&groupid=264 default_install_svirt online_upgrade_sles15sp4_vmware textmode_svirt ## Impact Vmware7.0 tests with svirt backend can't be passed. ## Problem In my local openqa env, some of job run passed. Not sure any difference in OSD env. default_install_svirt - http://10.67.129.96/tests/1875 online_upgrade_sles15sp4_vmware - http://10.67.129.96/tests/1873 ## Suggestion Increase the interval for establishing connections ## Workaround No