See https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/display/redirect
Changes:
------------------------------------------ [...truncated 546.95 KiB...] MTC@193bef2c5517: Test case TC_srv_connectClient_configClientBank finished. Verdict: pass MTC@193bef2c5517: 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'. [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 Apr 4 08:57:02 UTC 2025 [1;32m====== RemsimClient_Tests.TC_srv_connectClient_configClientBank pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4712) Waiting for packet dumper to finish... 1 (prev_count=4712, count=5704) MTC@193bef2c5517: 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@193bef2c5517: 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 ------ Fri Apr 4 08:57:04 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@193bef2c5517: 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@193bef2c5517: 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)@193bef2c5517: Established a new IPA connection (conn_id=2) RSPRO_SRV0(9)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(9)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@193bef2c5517: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(9)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(9)@193bef2c5517: 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:40629){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)@193bef2c5517: Final verdict of PTC: none MTC@193bef2c5517: 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)@193bef2c5517: Established a new IPA connection (conn_id=2) RSPRO_SRV0(11)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(11)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@193bef2c5517: setverdict(pass): none -> pass RSPRO_SRV0(11)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(11)@193bef2c5517: 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 RSPRO_SRV1(10)@193bef2c5517: 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:33439){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 [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(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 RSPRO_SRV0(11)@193bef2c5517: Final verdict of PTC: none MTC@193bef2c5517: Setting final verdict of the test case. MTC@193bef2c5517: Local verdict of MTC: pass MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass) MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass) MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass) MTC@193bef2c5517: Test case TC_srv_reconnect finished. Verdict: pass MTC@193bef2c5517: 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'. Fri Apr 4 08:57:14 UTC 2025 [1;32m====== RemsimClient_Tests.TC_srv_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=5096) Waiting for packet dumper to finish... 1 (prev_count=5096, count=5592) MTC@193bef2c5517: 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@193bef2c5517: 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 ------ Fri Apr 4 08:57:16 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@193bef2c5517: 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@193bef2c5517: 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)@193bef2c5517: Established a new IPA connection (conn_id=2) RSPRO_SRV0(12)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(12)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@193bef2c5517: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(12)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(12)@193bef2c5517: 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 MTC@193bef2c5517: setverdict(pass): none -> pass [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)@193bef2c5517: Established a new IPA connection (conn_id=2) RSPRO_SRV1(13)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(13)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(13)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(13)@193bef2c5517: 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:34353){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)@193bef2c5517: 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@193bef2c5517: 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)@193bef2c5517: Established a new IPA connection (conn_id=2) RSPRO_SRV1(14)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(14)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@193bef2c5517: setverdict(pass): pass -> pass, component reason not changed RSPRO_SRV1(14)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(14)@193bef2c5517: 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:37619){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:37725){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)@193bef2c5517: Final verdict of PTC: none RSPRO_SRV1(14)@193bef2c5517: Final verdict of PTC: none MTC@193bef2c5517: Setting final verdict of the test case. MTC@193bef2c5517: Local verdict of MTC: pass MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass) MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass) MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV1(14): none (pass -> pass) MTC@193bef2c5517: Test case TC_bank_reconnect finished. Verdict: pass MTC@193bef2c5517: 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(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 Apr 4 08:57:35 UTC 2025 [1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7236) Waiting for packet dumper to finish... 1 (prev_count=7236, count=8228) MTC@193bef2c5517: 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@193bef2c5517: 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 ------ Fri Apr 4 08:57:37 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@193bef2c5517: 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@193bef2c5517: 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)@193bef2c5517: Established a new IPA connection (conn_id=2) RSPRO_SRV0(15)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(15)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@193bef2c5517: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(15)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(15)@193bef2c5517: 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(16)@193bef2c5517: Established a new IPA connection (conn_id=2) MTC@193bef2c5517: setverdict(pass): none -> pass RSPRO_SRV1(16)@193bef2c5517: 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)@193bef2c5517: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(16)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(16)@193bef2c5517: 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)@193bef2c5517: 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)@193bef2c5517: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(16)@193bef2c5517: 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)@193bef2c5517: IPA: Closed Aborted RSPRO_SRV0(15)@193bef2c5517: IPA: Closed MTC@193bef2c5517: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO" RSPRO_SRV1(16)@193bef2c5517: Final verdict of PTC: none RSPRO_SRV0(15)@193bef2c5517: Final verdict of PTC: none MTC@193bef2c5517: Setting final verdict of the test case. MTC@193bef2c5517: Local verdict of MTC: fail reason: "Received unexpected != RPSRO" MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail) MTC@193bef2c5517: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail) MTC@193bef2c5517: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO MTC@193bef2c5517: 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'. Fri Apr 4 08:57:46 UTC 2025 [1;31m------ RemsimClient_Tests.TC_bank_disconnect fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4868) [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=4868, count=5604) MTC@193bef2c5517: 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@193bef2c5517: 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 ------ Fri Apr 4 08:57:48 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1465/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-20250404-0852-46d274af-1'][0m testenv-remsim-all-osmocom-latest-20250404-0852-46d274af-1 [1;34m[testenv][client] Stopping testsuite (2462387)[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/1465/artifa... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.