See <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/display/…>
Changes:
------------------------------------------
[...truncated 549.69 KiB...]
[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@203d26841a1b: Setting final verdict of the test case.
MTC@203d26841a1b: Local verdict of MTC: pass
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV0(7): none (pass -> pass)
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV1(8): none (pass -> pass)
MTC@203d26841a1b: Test case TC_srv_connectClient_configClientBank finished. Verdict: pass
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_srv_connectClient_configClientBank pass'.
Fri Apr 4 08:57:01 UTC 2025
[1;32m====== RemsimClient_Tests.TC_srv_connectClient_configClientBank pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4908)
Waiting for packet dumper to finish... 1 (prev_count=4908, count=5900)
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_srv_connectClient_configClientBank pass' was executed successfully (exit status: 0).
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_srv_reconnect'.
------ RemsimClient_Tests.TC_srv_reconnect ------
Fri Apr 4 08:57:03 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_srv_reconnect' was executed successfully (exit status: 0).
MTC@203d26841a1b: 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)@203d26841a1b: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(9)@203d26841a1b: 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)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(9)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@203d26841a1b: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired).
RSPRO_SRV0(9)@203d26841a1b: 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)@203d26841a1b: 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(9)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(9)@203d26841a1b: 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(server,r=127.0.0.1:9998<->l=127.0.0.1:43197){CONNECTED} connection closed with srv[0;m
[1;33mDRSPRO[0;m [1;33mNOTICE[0;m ../rspro_client_fsm.c:154 RSPRO_CLIENT(server){ESTABLISHED}: 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)@203d26841a1b: Final verdict of PTC: none
MTC@203d26841a1b: 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)@203d26841a1b: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(11)@203d26841a1b: 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)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(11)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV0(11)@203d26841a1b: 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 } } } }
MTC@203d26841a1b: setverdict(pass): none -> pass
RSPRO_SRV0(11)@203d26841a1b: 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)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(11)@203d26841a1b: 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:34349){CONNECTED} connection closed with srv[0;m
RSPRO_SRV1(10)@203d26841a1b: Final verdict of PTC: none
[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)@203d26841a1b: Final verdict of PTC: none
MTC@203d26841a1b: Setting final verdict of the test case.
MTC@203d26841a1b: Local verdict of MTC: pass
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass)
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass)
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV0(11): 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@203d26841a1b: Test case TC_srv_reconnect finished. Verdict: pass
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_srv_reconnect 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
Fri Apr 4 08:57:13 UTC 2025
[1;32m====== RemsimClient_Tests.TC_srv_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4912)
Waiting for packet dumper to finish... 1 (prev_count=4912, count=5408)
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_srv_reconnect pass' was executed successfully (exit status: 0).
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_reconnect'.
------ RemsimClient_Tests.TC_bank_reconnect ------
Fri Apr 4 08:57:15 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_reconnect' was executed successfully (exit status: 0).
MTC@203d26841a1b: 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)@203d26841a1b: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(12)@203d26841a1b: 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)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(12)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@203d26841a1b: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired).
RSPRO_SRV0(12)@203d26841a1b: 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)@203d26841a1b: 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(12)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(12)@203d26841a1b: 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
RSPRO_SRV1(13)@203d26841a1b: Established a new IPA connection (conn_id=2)
MTC@203d26841a1b: setverdict(pass): none -> pass
RSPRO_SRV1(13)@203d26841a1b: 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)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV1(13)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV1(13)@203d26841a1b: 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)@203d26841a1b: 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)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV1(13)@203d26841a1b: 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:45145){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)@203d26841a1b: 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@203d26841a1b: 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)@203d26841a1b: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(14)@203d26841a1b: 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)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV1(14)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@203d26841a1b: setverdict(pass): pass -> pass, component reason not changed
RSPRO_SRV1(14)@203d26841a1b: 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)@203d26841a1b: 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)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV1(14)@203d26841a1b: 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:39319){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:44947){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(14)@203d26841a1b: Final verdict of PTC: none
RSPRO_SRV0(12)@203d26841a1b: Final verdict of PTC: none
MTC@203d26841a1b: Setting final verdict of the test case.
MTC@203d26841a1b: Local verdict of MTC: pass
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass)
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV1(13): 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@203d26841a1b: Local verdict of PTC RSPRO_SRV1(14): none (pass -> pass)
MTC@203d26841a1b: Test case TC_bank_reconnect finished. Verdict: 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
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_reconnect pass'.
Fri Apr 4 08:57:34 UTC 2025
[1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7084)
Waiting for packet dumper to finish... 1 (prev_count=7084, count=8076)
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_reconnect pass' was executed successfully (exit status: 0).
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_disconnect'.
------ RemsimClient_Tests.TC_bank_disconnect ------
Fri Apr 4 08:57:36 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_disconnect' was executed successfully (exit status: 0).
MTC@203d26841a1b: 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)@203d26841a1b: Established a new IPA connection (conn_id=2)
RSPRO_SRV0(15)@203d26841a1b: 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)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV0(15)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
MTC@203d26841a1b: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired).
RSPRO_SRV0(15)@203d26841a1b: 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)@203d26841a1b: 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)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV0(15)@203d26841a1b: 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
[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
MTC@203d26841a1b: setverdict(pass): none -> pass
RSPRO_SRV1(16)@203d26841a1b: Established a new IPA connection (conn_id=2)
RSPRO_SRV1(16)@203d26841a1b: 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) } } } }
RSPRO_SRV1(16)@203d26841a1b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
RSPRO_SRV1(16)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
RSPRO_SRV1(16)@203d26841a1b: 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)@203d26841a1b: 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)@203d26841a1b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
RSPRO_SRV1(16)@203d26841a1b: 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)@203d26841a1b: IPA: Closed
RSPRO_SRV1(16)@203d26841a1b: Final verdict of PTC: none
Aborted
RSPRO_SRV0(15)@203d26841a1b: IPA: Closed
MTC@203d26841a1b: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO"
RSPRO_SRV0(15)@203d26841a1b: Final verdict of PTC: none
MTC@203d26841a1b: Setting final verdict of the test case.
MTC@203d26841a1b: Local verdict of MTC: fail reason: "Received unexpected != RPSRO"
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail)
MTC@203d26841a1b: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail)
MTC@203d26841a1b: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_disconnect fail'.
Fri Apr 4 08:57:45 UTC 2025
[1;31m------ RemsimClient_Tests.TC_bank_disconnect fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=5016)
[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
Waiting for packet dumper to finish... 1 (prev_count=5016, count=5752)
MTC@203d26841a1b: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_disconnect fail' was executed successfully (exit status: 0).
MTC@203d26841a1b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/ws/_cache/po…> RemsimClient_Tests.TC_bank_disconnect_reconnect'.
------ RemsimClient_Tests.TC_bank_disconnect_reconnect ------
Fri Apr 4 08:57:47 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-asan/301/artifact…> 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-nightly-asan-20250404-0852-000adb2d-1'][0m
testenv-remsim-all-osmocom-nightly-asan-20250404-0852-000adb2d-1
[1;34m[testenv][client] Stopping testsuite (2462065)[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-asan/301/artifact… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.