Comment 23 for bug 2023243

Revision history for this message
bugproxy (bugproxy) wrote : Comment bridged from LTC Bugzilla

------- Comment From <email address hidden> 2023-09-29 14:05 EDT-------
Attaching the latest error message and stack trace

neo@p8-110-neo:~$ mkvterm --id 8
[PVME03000147-0404] Virtual adapter defined for virtual slot number 5 is in use and cannot be removed.

Error creating a vterm device:

neo@p8-110-neo:~$ mkvterm --id 8
[PVME03000147-0404] Virtual adapter defined for virtual slot number 5 is in use and cannot be removed.

Error creating a vterm device:

neo@p8-110-neo:~$

root@p8-110-neo:/home/neo# /usr/sbin/mkvtermutil --id 8
+ . gettext.sh
++ test 'X\t' = 'X\t'
++ echo=echo
++ test -z ''
++ case "$0" in
+ hvcsadmin=/usr/sbin/hvcsadmin
+ hvcsdevlimit=1024
+ pvmutil=/usr/sbin/pvmutil
+ socat=/usr/bin/socat
+ rmvterm=/usr/sbin/rmvtermutil
+ openvt=/bin/openvt
+ '[' -f /usr/bin/linuxvnc ']'
+ linuxvnc=/usr/bin/linuxvnc
+ flock=/usr/bin/flock
+ modprobe=/sbin/modprobe
+ slattach=/sbin/slattach
+ ifconfig=/sbin/ifconfig
+ tcpdump=/usr/sbin/tcpdump
+ Xvfb=/usr/bin/Xvfb
+ x11vnc=/usr/bin/x11vnc
+ java=/opt/pvm-rest/lib/wlp/java/bin/java
+ xinit=/usr/bin/xinit
+ bash=/bin/bash
+ xterm=/usr/bin/xterm
+ pgrep=/usr/bin/pgrep
+ giveUsage=0
+ lparId=0
+ logFileDir=/var/log/pvm/mkvtermLogs
+ logFilePath=
+ lparIsIBMi=false
+ vnc=0
+ localVNC=0
+ ttyNumber=-1
+ vncPort=
+ vncPasswd=
+ vncDesktop=
+ lockFile=/tmp/vterm.lck
+ lockTimeout=15
+ lockFD=3
+ logFile=
+ socatParms=STDIO,raw,echo=0,escape=0x1d
+ socatParmsVNC=STDIO,raw,echo=0
+ useXterm=false
+ xtermParms='-fa xft:DejaVuSansMono:size=12 +sb -rv -e '
+ xtermSuffix1=' -- /usr/bin/Xvfb'
+ xtermSuffix2=' -screen 0 804x484x8 -nolisten tcp'
+ vncPortFile=/var/run/vterm_rfbport_lp
+ ptsLink=/var/run/5250_lp
+ socatParms5250=pty,raw,echo=0,link=/var/run/5250_lp
+ prefixLocal5250=10.252
+ prefixRemote5250=10.253
+ xvfbParms='-screen 0 976x615x8'
+ emulatorLocation=/opt/pvm/5250/
+ emulatorSettingsLocation=/etc/pvm/data/
+ emulatorSettingsLocationForLpar=/etc/pvm/data/lpar/
+ emulatorParms='-Xmx40m -cp /opt/pvm/5250/acshmchod.jar com.ibm.eNetwork.hmchod.HmcHod '
+ emulatorParmsSuffix1=' -port 2300 -title emulator_settings X X '
+ emulatorParmsSuffix2=' -- /usr/bin/Xvfb'
+ emulatorParmsSuffix3=' -screen 0 1024x768x24 -nolisten tcp'
+ RET_FAIL=1
+ RET_ITEMS_NOT_INSTALLED=2
+ RET_VTERM_ALREADY_STARTED=3
+ RET_OUT_OF_VTERMS=4
+ RET_USAGE=99
+ socatPid=-1
+ slattachPid=-1
+ declare -A lang_code
+ lang_code=([930]=0 [e930]=1 [939]=2 [1399]=3 [e1399]=4)
+ codePage=(930 930 939 1399 1399)
+ codePageKey=(KEY_JAPAN_KATAKANA KEY_JAPAN_KATAKANA_EX KEY_JAPAN_ENGLISH_EX KEY_JAPAN_LATIN_EXTENDED KEY_JAPAN_LATIN_EXTENDED_JIS2004)
+ fontName=(Monospaced)
+ pdfFont=(KozMinPro-Regular-Acro)
++ getopt -o h --long id:,help,vnc,local,passwdfile:,log:,vncport:,vncdesktop:,codepage: -- --id 8
+ TEMP=' --id '\''8'\'' --'
+ '[' 0 -ne 0 ']'
+ eval set -- ' --id '\''8'\'' --'
++ set -- --id 8 --
+ true
+ case "$1" in
+ lparId=8
+ shift 2
+ true
+ case "$1" in
+ break
+ [[ 8 -eq 0 ]]
+ [[ 8 =~ ^[0-9]+$ ]]
+ [[ ! -d /var/log/pvm/mkvtermLogs ]]
+ logFilePath=/var/log/pvm/mkvtermLogs/vtermLog_8.log
+ cat /dev/null
+ [[ -n '' ]]
+ [[ -n '' ]]
+ [[ -n '' ]]
+ /usr/sbin/pvmutil -o getrmcstatus
+ grep lpar_id=8,
+ grep os400
+ '[' 1 -eq 0 ']'
+ [[ -n '' ]]
+ [[ -n '' ]]
+ [[ -n '' ]]
+ [[ false = true ]]
+ [[ -n '' ]]
+ [[ false = false ]]
+ [[ -e /usr/bin/xinit ]]
+ [[ -e /usr/bin/Xvfb ]]
+ [[ -e /usr/bin/xterm ]]
+ [[ -e /usr/bin/x11vnc ]]
+ useXterm=true
++ expr 1024 - 1
+ '[' '!' -e /dev/hvcs1023 ']'
+ echo '/sbin/modprobe -r hvcs'
+ /sbin/modprobe -r hvcs
+ echo '/sbin/modprobe hvcs hvcs_parm_num_devs=1024'
+ /sbin/modprobe hvcs hvcs_parm_num_devs=1024
+ [[ false = true ]]
+ pvmhvcsadmin=/tmp/pvmhvcsadmin
+ '[' -s /tmp/pvmhvcsadmin ']'
+ hvcsadmin=/tmp/pvmhvcsadmin
+ chmod +x /tmp/pvmhvcsadmin
+ grep -F '=~ /.+(3\d+)$/' /tmp/pvmhvcsadmin
+ '[' 1 -eq 0 ']'
++ /usr/sbin/pvmutil -o create_vterm_dev --id 8
[PVME03000147-0404] Virtual adapter defined for virtual slot number 5 is in use and cannot be removed.

+ createDev=
+ '[' 1 -ne 0 ']'
+ gettext 'Error creating a vterm device:'
Error creating a vterm device:+ echo

+ echo

+ exit 1

Parms:
0000: 00010006 30000005 ....0...
Request:
0000: decaff20 00000021 00000000 80010080 ... ...!........
0010: 00000001 ff020000 00000000 00000000 ................
0020: 01 .
Response:
0000: decaff20 00000034 0001da92 80010040 ... ...4.......@
0010: 00000014 ff0280ee 00000000 00000000 ................
0020: 00010000 00100001 40000147 00010006 ........@..G....
0030: 30000005 0...

09/29/23 18:00:14.465.338 UTC DEBUG pvmutil[256489.132275266941984]: (common/accessClient/ApMsgTransporter.cpp:577) sockClose
09/29/23 18:00:14.465.415 UTC DEBUG pvmutil[256489.132275266941984]: (common/util/HmclMessagePool.cpp:209) HmclMessagePool status:
Number of available messages: 5
Number of outstanding messages: 0
Threshold: 0
Total checkouts: 63
Max checked out at one time: 5
Deleted messages: 0
Outstanding messages:

09/29/23 18:00:14.465.464 UTC INFO pvmutil[256489.132275266941984]: Command /usr/sbin/pvmutil returned 1. Additional messages: PVME03000147

09/29/23 18:00:14.465.561 UTC DEBUG pvmutil[256489.132275266941984]: (common/thread/HmclSynchronizedQueuePool.cpp:161) HmclSynchronizedQueuePool status:
Number of available queues: 1
Number of outstanding queues: 0
Total checkouts: 1
Max checked out at one time: 1
Outstanding queues:

Novalink IP: 9.47.78.65
Login: neo/neo123

------- Comment From <email address hidden> 2023-09-29 14:06 EDT-------
dmesg logs:
----------

nager/nm-dhcp-helper" pid=814 comm="apparmor_parser"
[Fri Sep 22 13:49:46 2023] audit: type=1400 audit(1695390588.340:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=814 comm="apparmor_parser"
[Fri Sep 22 13:49:46 2023] audit: type=1400 audit(1695390588.340:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/{,usr/}sbin/dhclient" pid=814 comm="apparmor_parser"
[Fri Sep 22 13:49:55 2023] ibmvmc: sethmcid: Set HMC ID: "neo 1"
[Fri Sep 22 13:49:56 2023] process 'rmcd' launched '/opt/rsct/bin/trspoolmgr' with NULL argv: empty string added
[Fri Sep 22 13:49:56 2023] fbcon: Taking over console
[Fri Sep 22 13:52:26 2023] hvcs: loading out-of-tree module taints kernel.
[Fri Sep 22 13:52:26 2023] hvcs: module verification failed: signature and/or required key missing - tainting kernel
[Fri Sep 22 13:52:26 2023] HVCS: Driver registered.
[Fri Sep 22 13:52:54 2023] HVCS: Driver registered.
[Fri Sep 22 13:52:54 2023] rpaphp: RPA HOT Plug PCI Controller Driver version: 0.1
[Fri Sep 22 13:52:55 2023] HVCS: Driver registered.
[Fri Sep 22 13:53:04 2023] HVCS: Driver registered.
[Fri Sep 22 13:53:05 2023] HVCS: Driver registered.
[Fri Sep 22 13:54:48 2023] HVCS: Driver registered.
[Fri Sep 22 13:54:49 2023] HVCS: Driver registered.
[Fri Sep 22 15:26:20 2023] HVCS: Driver registered.
[Fri Sep 22 15:26:53 2023] HVCS: Driver registered.
[Fri Sep 22 15:29:11 2023] HVCS: Driver registered.
[Fri Sep 22 16:34:30 2023] HVCS: Driver registered.
[Fri Sep 22 16:34:39 2023] HVCS: Driver registered.
[Fri Sep 22 16:34:43 2023] HVCS: Driver registered.
[Fri Sep 22 16:40:26 2023] HVCS: Driver registered.
[Fri Sep 22 16:40:32 2023] HVCS: Driver registered.
[Fri Sep 22 16:41:42 2023] HVCS: Driver registered.
[Fri Sep 22 17:38:24 2023] HVCS: Driver registered.
[Fri Sep 22 17:38:45 2023] HVCS: Driver registered.
[Fri Sep 22 17:41:09 2023] HVCS: Driver registered.
[Fri Sep 22 17:41:37 2023] HVCS: Driver registered.
[Fri Sep 22 17:43:58 2023] HVCS: Driver registered.
[Fri Sep 22 17:44:22 2023] HVCS: Driver registered.
[Fri Sep 22 17:57:22 2023] HVCS: Driver registered.
[Fri Sep 22 17:57:31 2023] HVCS: Driver registered.
[Fri Sep 22 17:58:27 2023] HVCS: Driver registered.
[Fri Sep 22 17:58:40 2023] HVCS: Driver registered.
[Fri Sep 22 18:01:08 2023] HVCS: Driver registered.
[Fri Sep 22 18:01:45 2023] HVCS: Driver registered.
[Fri Sep 22 18:04:03 2023] HVCS: Driver registered.
[Fri Sep 22 18:04:11 2023] HVCS: Driver registered.
[Fri Sep 22 18:04:21 2023] HVCS: Driver registered.
[Fri Sep 22 18:14:28 2023] HVCS: Driver registered.
[Fri Sep 22 18:15:05 2023] HVCS: Driver registered.
[Fri Sep 22 18:15:10 2023] HVCS: Driver registered.
[Fri Sep 22 18:17:25 2023] HVCS: Driver registered.
[Thu Sep 28 18:06:06 2023] HVCS: Driver registered.
[Fri Sep 29 17:49:48 2023] HVCS: Driver registered.
[Fri Sep 29 17:49:54 2023] HVCS: Driver registered.
[Fri Sep 29 17:53:54 2023] HVCS: Driver registered.
[Fri Sep 29 17:55:09 2023] HVCS: Driver registered.
[Fri Sep 29 17:58:58 2023] HVCS: Driver registered.
[Fri Sep 29 17:59:21 2023] HVCS: Driver registered.
[Fri Sep 29 17:59:58 2023] HVCS: Driver registered.