See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/display/redirect?page=changes>
Changes:
[Vadim Yanitskiy] msc: add/expect network-originated MT-Forward-SM.Err
------------------------------------------
[...truncated 542.89 KiB...]
MTC@8ef38554b051: Test case TC_srv_connectClient_configClientBank finished. Verdict: 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
[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@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_srv_connectClient_configClientBank pass'.
Tue Apr 8 13:38:30 UTC 2025
[1;32m====== RemsimClient_Tests.TC_srv_connectClient_configClientBank pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4612)
Waiting for packet dumper to finish... 1 (prev_count=4612, count=5604)
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_srv_connectClient_configClientBank pass' was executed
successfully (exit status: 0).
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_srv_reconnect'.
------ RemsimClient_Tests.TC_srv_reconnect ------
Tue Apr 8 13:38:32 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_srv_reconnect' was executed successfully (exit status: 0).
MTC@8ef38554b051: Test case TC_srv_reconnect 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(9)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(9)@8ef38554b051: 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(9)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(9)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV0(9)@8ef38554b051: 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(9)@8ef38554b051: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1),
data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag :=
IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
MTC@8ef38554b051: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active
(running or expired).
RSPRO_SRV0(9)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(9)@8ef38554b051: 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
[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:34889){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(9)@8ef38554b051: Final verdict of PTC: none
MTC@8ef38554b051: 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: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(11)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(11)@8ef38554b051: 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(11)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(11)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@8ef38554b051: setverdict(pass): none -> pass
RSPRO_SRV0(11)@8ef38554b051: 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(11)@8ef38554b051: 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(11)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(11)@8ef38554b051: 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
[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:43311){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(11)@8ef38554b051: Final verdict of PTC: none
[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
RSPRO_SRV1(10)@8ef38554b051: Final verdict of PTC: none
[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@8ef38554b051: Setting final verdict of the test case.
MTC@8ef38554b051: Local verdict of MTC: pass
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass)
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass)
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass)
MTC@8ef38554b051: Test case TC_srv_reconnect finished. Verdict: pass
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_srv_reconnect pass'.
Tue Apr 8 13:38:42 UTC 2025
[1;32m====== RemsimClient_Tests.TC_srv_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=5040)
Waiting for packet dumper to finish... 1 (prev_count=5040, count=5536)
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_srv_reconnect pass' was executed successfully (exit status: 0).
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_reconnect'.
------ RemsimClient_Tests.TC_bank_reconnect ------
Tue Apr 8 13:38:44 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_reconnect' was executed successfully (exit status: 0).
MTC@8ef38554b051: Test case TC_bank_reconnect 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: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(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
[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(12)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(12)@8ef38554b051: 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(12)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(12)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV0(12)@8ef38554b051: 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(12)@8ef38554b051: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1),
data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag :=
IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
MTC@8ef38554b051: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active
(running or expired).
RSPRO_SRV0(12)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(12)@8ef38554b051: 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
[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
MTC@8ef38554b051: setverdict(pass): none -> pass
RSPRO_SRV1(13)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(13)@8ef38554b051: 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(13)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV1(13)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV1(13)@8ef38554b051: 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(13)@8ef38554b051: 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(13)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV1(13)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
[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:35289){CONNECTED} connection closed
with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154
RSPRO_CLIENT(bankd){ESTABLISHED}: RSPRO link to 127.0.0.1:9999 DOWN
[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(13)@8ef38554b051: Final verdict of PTC: none
[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(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@8ef38554b051: 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)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(14)@8ef38554b051: 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)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV1(14)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@8ef38554b051: setverdict(pass): pass -> pass, component reason not changed
RSPRO_SRV1(14)@8ef38554b051: 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)@8ef38554b051: 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)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV1(14)@8ef38554b051: 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(server,r=127.0.0.1:9998<->l=127.0.0.1:36397){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
[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:38611){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_SRV0(12)@8ef38554b051: Final verdict of PTC: none
RSPRO_SRV1(14)@8ef38554b051: Final verdict of PTC: none
MTC@8ef38554b051: Setting final verdict of the test case.
MTC@8ef38554b051: Local verdict of MTC: pass
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass)
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass)
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV1(14): none (pass -> 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
MTC@8ef38554b051: Test case TC_bank_reconnect finished. Verdict: pass
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/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
[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
Tue Apr 8 13:39:03 UTC 2025
[1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7136)
Waiting for packet dumper to finish... 1 (prev_count=7136, count=8128)
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_reconnect pass' was executed successfully (exit status:
0).
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect'.
------ RemsimClient_Tests.TC_bank_disconnect ------
Tue Apr 8 13:39:05 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect' was executed successfully (exit status: 0).
MTC@8ef38554b051: Test case TC_bank_disconnect 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: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;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
[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(15)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(15)@8ef38554b051: 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)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(15)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@8ef38554b051: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active
(running or expired).
RSPRO_SRV0(15)@8ef38554b051: 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)@8ef38554b051: 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)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(15)@8ef38554b051: 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
[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
MTC@8ef38554b051: setverdict(pass): none -> pass
RSPRO_SRV1(16)@8ef38554b051: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(16)@8ef38554b051: 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)@8ef38554b051: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV1(16)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV1(16)@8ef38554b051: 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)@8ef38554b051: 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)@8ef38554b051: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV1(16)@8ef38554b051: 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
[1;32mDRSPRO[0;m [1;32mINFO[0;m ../rspro_client_fsm.c:428
RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server
Assert failed stream_cli_close(cli) == false stream_cli.c:1023
RSPRO_SRV1(16)@8ef38554b051: IPA: Closed
Aborted
RSPRO_SRV0(15)@8ef38554b051: IPA: Closed
RSPRO_SRV1(16)@8ef38554b051: Final verdict of PTC: none
MTC@8ef38554b051: setverdict(fail): pass -> fail reason: "Received unexpected !=
RPSRO", new component reason: "Received unexpected != RPSRO"
RSPRO_SRV0(15)@8ef38554b051: Final verdict of PTC: none
MTC@8ef38554b051: Setting final verdict of the test case.
MTC@8ef38554b051: Local verdict of MTC: fail reason: "Received unexpected !=
RPSRO"
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail)
MTC@8ef38554b051: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail)
MTC@8ef38554b051: Test case TC_bank_disconnect finished. Verdict: fail reason: Received
unexpected != RPSRO
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_disconnect fail'.
Tue Apr 8 13:39:14 UTC 2025
[1;31m------ RemsimClient_Tests.TC_bank_disconnect fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4968)
Waiting for packet dumper to finish... 1 (prev_count=4968, count=5704)
[1;34m[testenv][client] Looking for a coredump[0m
[0;94m[testenv][client] coredumpctl is not available, won't try to get coredump[0m
[1;91m[testenv][client] client unexpected exit during
RemsimClient_Tests.TC_bank_disconnect! rc=0[0m
[0;94m[testenv][client] Waiting until test has stopped...[0m
MTC@8ef38554b051: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
RemsimClient_Tests.TC_bank_disconnect fail' was executed successfully (exit status:
0).
MTC@8ef38554b051: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
RemsimClient_Tests.TC_bank_disconnect_reconnect'.
------ RemsimClient_Tests.TC_bank_disconnect_reconnect ------
Tue Apr 8 13:39:16 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][client] Stopping podman container[0m
[0;94m[testenv][client] + ['podman', 'kill',
'testenv-remsim-all-osmocom-latest-20250408-1334-af4c792f-1'][0m
testenv-remsim-all-osmocom-latest-20250408-1334-af4c792f-1
[1;34m[testenv][client] Stopping testsuite (2558981)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][client] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1470/artif… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.