Project

General

Profile

action #62819

2nd select_console() call failed with pvm_hmc backend

Added by michel_mno about 1 year ago. Updated about 1 year ago.

Status:
New
Priority:
Low
Assignee:
-
Category:
Feature requests
Target version:
Start date:
2020-01-30
Due date:
% Done:

0%

Estimated time:
Difficulty:

Description

2nd select_console() call failed with pvm_hmc backend

I am testing for the first time the "install_only" test on my own ppc64le openQA machine with specific worker configured to access a LPAR P8 with pvm_hmc backend.

test failure when installation_overview is calling select_console 'install-shell' which is the 2nd call as per extract of attached autoinst.log (p8lpar_install_shell_not_matched_autoinst.log)

[2020-01-30T10:49:56.342 CET] [debug] /var/lib/openqa/share/tests/opensuse/tests/installation/bootloader.pm:45 called bootloader_pvm::boot_pvm
[2020-01-30T10:49:56.343 CET] [debug] <<< testapi::select_console(testapi_console="install-shell")
...
[2020-01-30T10:50:02.550 CET] [debug] <<< testapi::select_console(testapi_console="root-ssh")
[2020-01-30T10:50:03.698 CET] [debug] <<< backend::baseclass::start_ssh_serial(username="root", hostname="furax2.tlslab.ibm.com", password="SECRET")
[2020-01-30T10:50:03.699 CET] [debug] <<< backend::baseclass::new_ssh_connection(username="root", password="SECRET", hostname="furax2.tlslab.ibm.com")
[2020-01-30T10:50:03.765 CET] [debug] SSH connection to root@furax2.tlslab.ibm.com established
[2020-01-30T10:50:03.810 CET] [debug] ssh xterm vt: grabbing serial console
[2020-01-30T10:50:03.861 CET] [debug] led state 0 0 0 -261
[2020-01-30T10:50:03.870 CET] [debug] activate_console, console: root-ssh, type: ssh
[2020-01-30T10:50:03.870 CET] [debug] <<< testapi::assert_screen(mustmatch="password-prompt", timeout=30)
[2020-01-30T10:50:05.011 CET] [debug] >>> testapi::_handle_found_needle: found password-prompt-20141207, similarity 1.00 @ 0/32
[2020-01-30T10:50:05.012 CET] [debug] <<< testapi::type_string(string="SECRET STRING", max_interval=100, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2020-01-30T10:50:06.215 CET] [debug] <<< testapi::send_key(key="ret", wait_screen_change=0, do_wait=0)
[2020-01-30T10:50:06.483 CET] [debug] <<< testapi::assert_screen(mustmatch=[
"text-logged-in-root",
"text-login"
[2020-01-30T10:50:06.583 CET] [debug] no match: 59.9s, best candidate: text-logged-in-root-20170321 (0.73)
[2020-01-30T10:50:29.519 CET] [debug] no match: 36.9s, best candidate: text-logged-in-root-simple_prompt-20190301 (0.67)
[2020-01-30T10:50:31.147 CET] [debug] >>> testapi::_handle_found_needle: found text-logged-in-root-simple_prompt-20190301, similarity 1.00 @ 88/224
[2020-01-30T10:50:31.147 CET] [debug] <<< testapi::type_string(string="which tput 2>&1 && PS1=\"\\[\$(tput bold 2; tput setaf 1)\\]#\\[\$(tput sgr0)\\] \"\n", max_interval=250, wait_screen_changes=0, wait_still_screen=0, timeout=30, similarity_level=47)
[2020-01-30T10:50:33.954 CET] [debug] /var/lib/openqa/share/tests/opensuse/tests/installation/bootloader.pm:45 called bootloader_pvm::boot_pvm
[2020-01-30T10:50:33.954 CET] [debug] <<< testapi::assert_screen(mustmatch="install-shell", no_wait=1, timeout=30)
[2020-01-30T10:50:33.969 CET] [debug] no match: 29.9s, best candidate: inst-console-20190625 (0.00)
[2020-01-30T10:50:37.901 CET] [debug] no change: 26.0s
[2020-01-30T10:50:38.203 CET] [debug] >>> testapi::_handle_found_needle: found inst-console-20190625, similarity 1.00 @ 72/225
...
[2020-01-30T10:52:49.216 CET] [debug] >>> testapi::_handle_found_needle: found installation_overview-ssh-open-20200130, similarity 1.00 @ 309/700
[2020-01-30T10:52:49.216 CET] [debug] /var/lib/openqa/share/tests/opensuse/tests/installation/installation_overview.pm:88 called testapi::select_console
[2020-01-30T10:52:49.216 CET] [debug] <<< testapi::select_console(testapi_console="install-shell")
[2020-01-30T10:52:49.223 CET] [debug] /var/lib/openqa/share/tests/opensuse/tests/installation/installation_overview.pm:88 called testapi::select_console
[2020-01-30T10:52:49.223 CET] [debug] <<< testapi::assert_screen(mustmatch="install-shell", no_wait=1, timeout=30)
[2020-01-30T10:52:49.259 CET] [debug] no match: 29.9s, best candidate: inst-console-20190625 (0.00)
...
[2020-01-30T10:53:19.752 CET] [debug] >>> testapi::_check_backend_response: match=install-shell timed out after 30 (assert_screen)
[2020-01-30T10:53:19.814 CET] [debug] no candidate needle with tag(s) 'install-shell' matched

History

#1 Updated by michel_mno about 1 year ago

test done with openSUSE TW snapshot 20200125 on an openQA ppc64le server with Leap15.1 and following openQA rpms:

$rpm -qa | grep -iE 'qemu.3|autoinst.4|openqa.4|python.*openqa|kernel.5' |sort
openQA-4.6.1580320481.7189339b0-lp151.2211.1.noarch
os-autoinst-4.6.1580337089.6f2b5c86-lp151.327.1.ppc64le
python3-openqa_client-1.3.0-lp151.5.1.noarch
python-openqa_client-1.3.0-5.1.noarch
qemu-3.1.1.1-lp151.7.9.1.ppc64le

I did this first LPAR test on openSUSE, because information from SUSE team that openQA is working for LPAR P9 (emails from Diego Assencio and Nick Singer)

#2 Updated by mgriessmeier about 1 year ago

Hey, can you post a link to your job please? from the logs it's hard to tell what the actual issue is (looks just like the needle failed to match)

#3 Updated by michel_mno about 1 year ago

I am not able to put a link to my job, because my test was done in the IBM intranet.
Let me know what specific data you will need ?

As summarized by previous autoinst.log extract the first select_console is in bootloader_pvm.pm and 2nd one in installation_overview.pm
https://github.com/os-autoinst/os-autoinst-distri-opensuse/blob/master/lib/bootloader_pvm.pm#L119 select_console('install-shell');
https://github.com/os-autoinst/os-autoinst-distri-opensuse/blob/master/tests/installation/installation_overview.pm#L88 select_console 'install-shell';

I assume the 2nd call to select-console() still consider the session as active, while seems to be a new ssh connection.
https://github.com/os-autoinst/os-autoinst/blob/master/testapi.pm#L1602 sub select_console {

#4 Updated by okurz about 1 year ago

  • Category set to Feature requests
  • Priority changed from Normal to Low
  • Target version set to future

As "pvm_hmc" is a pretty recent addition I consider this as "feature request" to cover that. Also I don't see needs for the QA Tools team to look into this soon so "Low" and "future" for now. Of course, as always, this should have no impact on any contribution any time by anyone.

Also available in: Atom PDF