See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/display/redirect>
Changes:
------------------------------------------
[...truncated 420.91 KiB...]
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(bankd,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 DOWN
MTC@525c844e9a97: Warning: Re-starting timer g_rspro_srv_Tguard, which is
already active (running or expired).
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:145
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 UP
RSPRO_SRV1(14)@525c844e9a97: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(14)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := {
get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag :=
IPAC_IDTAG_UNITID (8) } } } }
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:199
RSPRO_CLIENT(bankd){ESTABLISHED}: received ID_GET for unit ID 0/0/0
RSPRO_SRV1(14)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
RSPRO_SRV1(14)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
MTC@525c844e9a97: setverdict(pass): pass -> pass, component reason not changed
RSPRO_SRV1(14)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data :=
'30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID
(8), data := '302F302F3000'O } } } }
RSPRO_SRV1(14)@525c844e9a97: IPA ID RESP: { { len := 19, tag :=
IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
RSPRO_SRV1(14)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
RSPRO_SRV1(14)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
SET_ATR: 3b 00
[38;5;23m[1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
stream_cli.c:547 CLICONN(bankd,r=127.0.0.1:9999<->l=127.0.0.1:44527){CONNECTED}
connection closed with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){CONNECTED}: RSPRO link to 127.0.0.1:9999 DOWN
[1;31mDMAIN[0;m [1;31mERROR[0;m main_fsm.c:237
CLIENT_MAIN(main){OPERATIONAL}: Connection to bankd lost
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server
[38;5;23m[1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
stream_cli.c:547
CLICONN(server,r=127.0.0.1:9998<->l=127.0.0.1:46109){CONNECTED} connection
closed with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(server){CONNECTED}: RSPRO link to 127.0.0.1:9998 DOWN
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(server){REESTABLISH_DELAY}: Destroying existing connection to
server
RSPRO_SRV1(14)@525c844e9a97: Final verdict of PTC: none
RSPRO_SRV0(12)@525c844e9a97: Final verdict of PTC: none
MTC@525c844e9a97: Setting final verdict of the test case.
MTC@525c844e9a97: Local verdict of MTC: pass
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass)
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass)
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV1(14): none (pass -> pass)
MTC@525c844e9a97: Test case TC_bank_reconnect finished. Verdict: pass
MTC@525c844e9a97: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_reconnect pass'.
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9998
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(server,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(bankd,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 DOWN
Fri May 16 10:18:14 UTC 2025
[1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7172)
Waiting for packet dumper to finish... 1 (prev_count=7172, count=8164)
MTC@525c844e9a97: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_reconnect pass' was executed successfully (exit
status: 0).
MTC@525c844e9a97: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect'.
------ RemsimClient_Tests.TC_bank_disconnect ------
Fri May 16 10:18:16 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap";>
><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@525c844e9a97: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect' was executed successfully (exit status:
0).
MTC@525c844e9a97: Test case TC_bank_disconnect started.
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(server){REESTABLISH_DELAY}: Destroying existing connection to
server
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9998
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:145
RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 UP
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(bankd,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 DOWN
RSPRO_SRV0(15)@525c844e9a97: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(15)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := {
get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag :=
IPAC_IDTAG_UNITID (8) } } } }
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:199
RSPRO_CLIENT(server){ESTABLISHED}: received ID_GET for unit ID 0/0/0
RSPRO_SRV0(15)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
RSPRO_SRV0(15)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
RSPRO_SRV0(15)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data :=
'30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID
(8), data := '302F302F3000'O } } } }
RSPRO_SRV0(15)@525c844e9a97: IPA ID RESP: { { len := 19, tag :=
IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
RSPRO_SRV0(15)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
MTC@525c844e9a97: Warning: Re-starting timer g_rspro_srv_Tguard, which is
already active (running or expired).
RSPRO_SRV0(15)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
[1;31mDMAIN[0;m [1;31mERROR[0;m ../rspro_client_fsm.c:378
CLIENT_MAIN(main){WAIT_BANKD}: Event MF_E_SRVC_CONNECTED not permitted
[1;32mDMAIN[0;m [1;32mINFO[0;m main_fsm.c:249
CLIENT_MAIN(main){WAIT_BANKD}: Rx configClientBankReq(127.0.0.1:9999 / B1:0)
[1;31mDMAIN[0;m [1;31mERROR[0;m main_fsm.c:255
CLIENT_MAIN(main){WAIT_BANKD}: transition to state WAIT_BANKD not permitted!
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server
MTC@525c844e9a97: setverdict(pass): none -> pass
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:145
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 UP
RSPRO_SRV1(16)@525c844e9a97: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(16)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := {
get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag :=
IPAC_IDTAG_UNITID (8) } } } }
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:199
RSPRO_CLIENT(bankd){ESTABLISHED}: received ID_GET for unit ID 0/0/0
RSPRO_SRV1(16)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
RSPRO_SRV1(16)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
RSPRO_SRV1(16)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data :=
'30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID
(8), data := '302F302F3000'O } } } }
RSPRO_SRV1(16)@525c844e9a97: IPA ID RESP: { { len := 19, tag :=
IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
RSPRO_SRV1(16)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
RSPRO_SRV1(16)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
SET_ATR: 3b 00
[1;32mDMAIN[0;m [1;32mINFO[0;m main_fsm.c:249
CLIENT_MAIN(main){OPERATIONAL}: Rx configClientBankReq(0.0.0.0:0 / B1:0)
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:536
RSPRO_CLIENT(bankd){CONNECTED}: Destroying existing connection to server
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){CONNECTED}: RSPRO link to 0.0.0.0:0 DOWN
[1;31mDMAIN[0;m [1;31mERROR[0;m ../rspro_client_fsm.c:405
CLIENT_MAIN(main){UNCONFIGURED}: Event MF_E_BANKD_LOST not permitted
RSPRO_SRV1(16)@525c844e9a97: IPA: Closed
MTC@525c844e9a97: setverdict(pass): pass -> pass, component reason not changed
MTC@525c844e9a97: setverdict(pass): pass -> pass, component reason not changed
RSPRO_SRV1(16)@525c844e9a97: Final verdict of PTC: none
[38;5;23m[1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
stream_cli.c:547
CLICONN(server,r=127.0.0.1:9998<->l=127.0.0.1:37797){CONNECTED} connection
closed with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(server){CONNECTED}: RSPRO link to 127.0.0.1:9998 DOWN
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(server){REESTABLISH_DELAY}: Destroying existing connection to
server
RSPRO_SRV0(15)@525c844e9a97: Final verdict of PTC: none
MTC@525c844e9a97: Setting final verdict of the test case.
MTC@525c844e9a97: Local verdict of MTC: pass
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV0(15): none (pass -> pass)
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9998
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV1(16): none (pass -> pass)
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(server,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN
MTC@525c844e9a97: Test case TC_bank_disconnect finished. Verdict: pass
MTC@525c844e9a97: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_disconnect pass'.
Fri May 16 10:18:25 UTC 2025
[1;32m====== RemsimClient_Tests.TC_bank_disconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4992)
Waiting for packet dumper to finish... 1 (prev_count=4992, count=6144)
MTC@525c844e9a97: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_disconnect pass' was executed successfully (exit
status: 0).
MTC@525c844e9a97: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect_reconnect'.
------ RemsimClient_Tests.TC_bank_disconnect_reconnect ------
Fri May 16 10:18:27 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap";>
><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@525c844e9a97: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect_reconnect' was executed successfully
(exit status: 0).
MTC@525c844e9a97: Test case TC_bank_disconnect_reconnect started.
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(server){REESTABLISH_DELAY}: Destroying existing connection to
server
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9998
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:145
RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 UP
RSPRO_SRV0(17)@525c844e9a97: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(17)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := {
get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag :=
IPAC_IDTAG_UNITID (8) } } } }
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:199
RSPRO_CLIENT(server){ESTABLISHED}: received ID_GET for unit ID 0/0/0
RSPRO_SRV0(17)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
RSPRO_SRV0(17)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
RSPRO_SRV0(17)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data :=
'30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID
(8), data := '302F302F3000'O } } } }
RSPRO_SRV0(17)@525c844e9a97: IPA ID RESP: { { len := 19, tag :=
IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
RSPRO_SRV0(17)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
RSPRO_SRV0(17)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
MTC@525c844e9a97: Warning: Re-starting timer g_rspro_srv_Tguard, which is
already active (running or expired).
[1;31mDMAIN[0;m [1;31mERROR[0;m ../rspro_client_fsm.c:378
CLIENT_MAIN(main){UNCONFIGURED}: Event MF_E_SRVC_CONNECTED not permitted
[1;32mDMAIN[0;m [1;32mINFO[0;m main_fsm.c:249
CLIENT_MAIN(main){UNCONFIGURED}: Rx configClientBankReq(127.0.0.1:9999 / B1:0)
MTC@525c844e9a97: setverdict(pass): none -> pass
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:145
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 UP
RSPRO_SRV1(18)@525c844e9a97: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(18)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := {
get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag :=
IPAC_IDTAG_UNITID (8) } } } }
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:199
RSPRO_CLIENT(bankd){ESTABLISHED}: received ID_GET for unit ID 0/0/0
RSPRO_SRV1(18)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
RSPRO_SRV1(18)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
RSPRO_SRV1(18)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data :=
'30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID
(8), data := '302F302F3000'O } } } }
RSPRO_SRV1(18)@525c844e9a97: IPA ID RESP: { { len := 19, tag :=
IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
RSPRO_SRV1(18)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
RSPRO_SRV1(18)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
SET_ATR: 3b 00
[1;32mDMAIN[0;m [1;32mINFO[0;m main_fsm.c:249
CLIENT_MAIN(main){OPERATIONAL}: Rx configClientBankReq(0.0.0.0:0 / B1:0)
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:536
RSPRO_CLIENT(bankd){CONNECTED}: Destroying existing connection to server
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){CONNECTED}: RSPRO link to 0.0.0.0:0 DOWN
[1;31mDMAIN[0;m [1;31mERROR[0;m ../rspro_client_fsm.c:405
CLIENT_MAIN(main){UNCONFIGURED}: Event MF_E_BANKD_LOST not permitted
RSPRO_SRV1(18)@525c844e9a97: IPA: Closed
MTC@525c844e9a97: setverdict(pass): pass -> pass, component reason not changed
RSPRO_SRV1(18)@525c844e9a97: Final verdict of PTC: none
MTC@525c844e9a97: Warning: Re-starting timer g_rspro_srv_Tguard, which is
already active (running or expired).
[1;32mDMAIN[0;m [1;32mINFO[0;m main_fsm.c:249
CLIENT_MAIN(main){UNCONFIGURED}: Rx configClientBankReq(127.0.0.1:9999 / B1:0)
MTC@525c844e9a97: setverdict(pass): pass -> pass, component reason not changed
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:145
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 UP
RSPRO_SRV1(19)@525c844e9a97: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(19)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := {
get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag :=
IPAC_IDTAG_UNITID (8) } } } }
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:199
RSPRO_CLIENT(bankd){ESTABLISHED}: received ID_GET for unit ID 0/0/0
RSPRO_SRV1(19)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
RSPRO_SRV1(19)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
RSPRO_SRV1(19)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data :=
'30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID
(8), data := '302F302F3000'O } } } }
RSPRO_SRV1(19)@525c844e9a97: IPA ID RESP: { { len := 19, tag :=
IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
MTC@525c844e9a97: setverdict(pass): pass -> pass, component reason not changed
RSPRO_SRV1(19)@525c844e9a97: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
RSPRO_SRV1(19)@525c844e9a97: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
RSPRO_SRV1(19)@525c844e9a97: Warning: The last outgoing messages on port
IPA_RSPRO_PORT may be lost.
[38;5;23m[1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
stream_cli.c:547
CLICONN(server,r=127.0.0.1:9998<->l=127.0.0.1:45697){CONNECTED} connection
closed with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(server){CONNECTED}: RSPRO link to 127.0.0.1:9998 DOWN
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(server){REESTABLISH_DELAY}: Destroying existing connection to
server
RSPRO_SRV0(17)@525c844e9a97: Final verdict of PTC: none
SET_ATR: 3b 00
[38;5;23m[1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
stream_cli.c:547 CLICONN(bankd,r=127.0.0.1:9999<->l=127.0.0.1:46461){CONNECTED}
connection closed with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){CONNECTED}: RSPRO link to 127.0.0.1:9999 DOWN
[1;31mDMAIN[0;m [1;31mERROR[0;m main_fsm.c:237
CLIENT_MAIN(main){OPERATIONAL}: Connection to bankd lost
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server
RSPRO_SRV1(19)@525c844e9a97: Final verdict of PTC: none
MTC@525c844e9a97: Setting final verdict of the test case.
MTC@525c844e9a97: Local verdict of MTC: pass
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV0(17): none (pass -> pass)
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV1(18): none (pass -> pass)
MTC@525c844e9a97: Local verdict of PTC RSPRO_SRV1(19): none (pass -> pass)
MTC@525c844e9a97: Test case TC_bank_disconnect_reconnect finished. Verdict: pass
MTC@525c844e9a97: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_disconnect_reconnect pass'.
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9999
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:454
RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at
127.0.0.1:9998
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(bankd,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){REESTABLISH}: RSPRO link to 127.0.0.1:9999 DOWN
[38;5;23m[1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
stream_cli.c:423 CLICONN(server,){CONNECTING} connect failed (-111)[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN
Fri May 16 10:18:36 UTC 2025
[1;32m====== RemsimClient_Tests.TC_bank_disconnect_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4720)
Waiting for packet dumper to finish... 1 (prev_count=4720, count=8580)
MTC@525c844e9a97: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_disconnect_reconnect pass' was executed
successfully (exit status: 0).
MC@525c844e9a97: Test execution finished.
Execution of [EXECUTE] section finished.
emtc
MC@525c844e9a97: Terminating MTC.
MC@525c844e9a97: MTC terminated.
MC2> exit
MC@525c844e9a97: Shutting down session.
MC@525c844e9a97: Shutdown complete.
No expected results found, not comparing outcome.
(<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/remsim/expected-results.xml)>
[0;94m[testenv][client] Testsuite is done[0m
[1;34m[testenv][client] Stopping testsuite (3659221)[0m
[1;34m[testenv][client] Merging log files[0m
[0;94m[testenv][client] + ['podman', 'exec', '-e',
'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e',
'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache',>
'-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e',
'TERM=dumb', '-e',
'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/remsim>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin',
'-e', 'HOME=/home/osmocom-build', '-t', '-w',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/client/testsuite',>
'testenv-remsim-all-osmocom-nightly-20250516-1013-cecd3329-1', 'sh', '-c',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/log_merge.sh>
REMSIM_Tests --rm >/dev/null'][0m
[1;34m[testenv][client] Formatting log files[0m
[0;94m[testenv][client] + ['podman', 'exec', '-e',
'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e',
'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache',>
'-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e',
'TERM=dumb', '-e',
'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/remsim>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin',
'-e', 'HOME=/home/osmocom-build', '-t', '-w',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/client/testsuite',>
'testenv-remsim-all-osmocom-nightly-20250516-1013-cecd3329-1', 'sh', '-c',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/scripts/log_format.sh'][0m>
[1;34m[testenv][client] Stopping client (3659175)[0m
[1;34m[testenv] Stopping podman container (restart)[0m
[0;94m[testenv] + ['podman', 'kill',
'testenv-remsim-all-osmocom-nightly-20250516-1013-cecd3329-1'][0m
testenv-remsim-all-osmocom-nightly-20250516-1013-cecd3329-1
[0;94m[testenv] + ['podman', 'run', '--rm', '--name',
'testenv-remsim-all-osmocom-nightly-20250516-1013-cecd3329-2', '--log-driver',
'json-file', '--log-opt',
'path=<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/podman/testenv-remsim-all-osmocom-nightly-20250516-1013-cecd3329-2.log',>
'--security-opt=seccomp=<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/podman/seccomp.json',>
'--cap-add=NET_ADMIN', '--cap-add=NET_RAW', '--device=/dev/net/tun',
'--volume',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/var-cache-apt>:/var/cache/apt',
'--volume',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/var-lib-apt>:/var/lib/apt',
'--sysctl', 'net.ipv4.conf.all.send_redirects=0', '--sysctl',
'net.ipv4.conf.default.send_redirects=0', '-e', 'PODMAN=1', '-e',
'TESTENV_BINARY_REPO=1', '--volume',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/logs>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/logs',>
'--volume',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache',>
'--volume',
'/home/osmocom-build/ccache/testenv:/home/osmocom-build/ccache/testenv',
'--volume',
'/home/osmocom-build/jenkins/workspace:/home/osmocom-build/jenkins/workspace',
'debian-bookworm-osmo-ttcn3-testenv',
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/data/scripts/testenv-podman-main.sh'][0m>
Running testenv-podman-main.sh
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
Traceback (most recent call last):
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/./testenv.py";,>
line 134, in <module>
main()
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/./testenv.py";,>
line 123, in main
run()
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/./testenv.py";,>
line 70, in run
testenv.podman.stop(True)
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/testenv/podman.py";,>
line 352, in stop
start()
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/testenv/podman.py";,>
line 277, in start
start_in_background(cmd)
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/testenv/podman.py";,>
line 203, in start_in_background
wait_until_started()
File
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_testenv/testenv/podman.py";,>
line 193, in wait_until_started
raise RuntimeError("Podman failed to start")
RuntimeError: Podman failed to start
[1;34m[testenv] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1754/artifact/logs/
[0m
+ RC=1
+ rm -rf _cache .linux
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 1.11, 0.79, 1.71[m[K
+ exit 1
Process leaked file descriptors. See
https://www.jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors
for more information
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
