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.
See https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/display/redirect?page=changes
Changes:
[Oliver Smith] testenv: fix building osmo-remsim with osmo-dev
------------------------------------------ [...truncated 547.06 KiB...] MTC@4536a99ac5ba: Setting final verdict of the test case. MTC@4536a99ac5ba: Local verdict of MTC: pass MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV0(7): none (pass -> pass) MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV1(8): none (pass -> pass) MTC@4536a99ac5ba: Test case TC_srv_connectClient_configClientBank finished. Verdict: pass MTC@4536a99ac5ba: 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'. Fri Apr 4 13:38:36 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@4536a99ac5ba: 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@4536a99ac5ba: 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 13:38:38 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@4536a99ac5ba: 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@4536a99ac5ba: Test case TC_srv_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: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(9)@4536a99ac5ba: Established a new IPA connection (conn_id=2) RSPRO_SRV0(9)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(9)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(9)@4536a99ac5ba: 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)@4536a99ac5ba: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MTC@4536a99ac5ba: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(9)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(9)@4536a99ac5ba: 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:35683){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)@4536a99ac5ba: Final verdict of PTC: none MTC@4536a99ac5ba: 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)@4536a99ac5ba: Established a new IPA connection (conn_id=2) RSPRO_SRV0(11)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(11)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@4536a99ac5ba: setverdict(pass): none -> pass RSPRO_SRV0(11)@4536a99ac5ba: 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)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(11)@4536a99ac5ba: 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:34363){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)@4536a99ac5ba: Final verdict of PTC: none RSPRO_SRV1(10)@4536a99ac5ba: 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 [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@4536a99ac5ba: Setting final verdict of the test case. MTC@4536a99ac5ba: Local verdict of MTC: pass MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass) MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass) MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass) MTC@4536a99ac5ba: Test case TC_srv_reconnect finished. Verdict: pass MTC@4536a99ac5ba: 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 13:38:48 UTC 2025 [1;32m====== RemsimClient_Tests.TC_srv_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4940) Waiting for packet dumper to finish... 1 (prev_count=4940, count=5436) MTC@4536a99ac5ba: 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@4536a99ac5ba: 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 13:38:50 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@4536a99ac5ba: 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@4536a99ac5ba: 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)@4536a99ac5ba: Established a new IPA connection (conn_id=2) RSPRO_SRV0(12)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(12)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(12)@4536a99ac5ba: 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)@4536a99ac5ba: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MTC@4536a99ac5ba: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(12)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(12)@4536a99ac5ba: 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)@4536a99ac5ba: Established a new IPA connection (conn_id=2) MTC@4536a99ac5ba: setverdict(pass): none -> pass RSPRO_SRV1(13)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(13)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(13)@4536a99ac5ba: 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)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(13)@4536a99ac5ba: 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:44435){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)@4536a99ac5ba: 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@4536a99ac5ba: 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)@4536a99ac5ba: Established a new IPA connection (conn_id=2) RSPRO_SRV1(14)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(14)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@4536a99ac5ba: setverdict(pass): pass -> pass, component reason not changed RSPRO_SRV1(14)@4536a99ac5ba: 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)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(14)@4536a99ac5ba: 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:34879){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:39227){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)@4536a99ac5ba: Final verdict of PTC: none RSPRO_SRV1(14)@4536a99ac5ba: 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 MTC@4536a99ac5ba: Setting final verdict of the test case. MTC@4536a99ac5ba: Local verdict of MTC: pass MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass) MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass) MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV1(14): none (pass -> pass) MTC@4536a99ac5ba: 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@4536a99ac5ba: 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'. Fri Apr 4 13:39:09 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@4536a99ac5ba: 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@4536a99ac5ba: 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 13:39:11 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@4536a99ac5ba: 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@4536a99ac5ba: 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)@4536a99ac5ba: Established a new IPA connection (conn_id=2) RSPRO_SRV0(15)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(15)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@4536a99ac5ba: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(15)@4536a99ac5ba: 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)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(15)@4536a99ac5ba: 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)@4536a99ac5ba: Established a new IPA connection (conn_id=2) MTC@4536a99ac5ba: setverdict(pass): none -> pass RSPRO_SRV1(16)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(16)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(16)@4536a99ac5ba: 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)@4536a99ac5ba: 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)@4536a99ac5ba: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(16)@4536a99ac5ba: 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 Aborted RSPRO_SRV1(16)@4536a99ac5ba: IPA: Closed RSPRO_SRV0(15)@4536a99ac5ba: IPA: Closed RSPRO_SRV1(16)@4536a99ac5ba: Final verdict of PTC: none MTC@4536a99ac5ba: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO" RSPRO_SRV0(15)@4536a99ac5ba: Final verdict of PTC: none MTC@4536a99ac5ba: Setting final verdict of the test case. MTC@4536a99ac5ba: Local verdict of MTC: fail reason: "Received unexpected != RPSRO" MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail) MTC@4536a99ac5ba: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail) MTC@4536a99ac5ba: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO MTC@4536a99ac5ba: 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 13:39:20 UTC 2025 [1;31m------ RemsimClient_Tests.TC_bank_disconnect fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4768) [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=4768, count=5504) MTC@4536a99ac5ba: 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@4536a99ac5ba: 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 13:39:22 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1466/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-1334-66fdff17-1'][0m testenv-remsim-all-osmocom-latest-20250404-1334-66fdff17-1 [1;34m[testenv][client] Stopping testsuite (1939919)[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/1466/artifa... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
See https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/display/redirect?page=changes
Changes:
[Oliver Smith] Cosmetic: testenv/testdir: fix formatting
[Pau Espin Pedrol] stp: TC_tmt_loadshare*: Use new vty command 'binding-table reset'
------------------------------------------ [...truncated 546.89 KiB...] [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@c5e2e7ca8e71: Setting final verdict of the test case. MTC@c5e2e7ca8e71: Local verdict of MTC: pass MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV0(7): none (pass -> pass) MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV1(8): none (pass -> pass) MTC@c5e2e7ca8e71: Test case TC_srv_connectClient_configClientBank finished. Verdict: pass MTC@c5e2e7ca8e71: 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'. Sat Apr 5 13:38:41 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@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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 ------ Sat Apr 5 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/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@c5e2e7ca8e71: 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@c5e2e7ca8e71: Test case TC_srv_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: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(9)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) RSPRO_SRV0(9)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(9)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@c5e2e7ca8e71: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(9)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(9)@c5e2e7ca8e71: 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:44119){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)@c5e2e7ca8e71: Final verdict of PTC: none MTC@c5e2e7ca8e71: 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)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) RSPRO_SRV0(11)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(11)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@c5e2e7ca8e71: setverdict(pass): none -> pass RSPRO_SRV0(11)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(11)@c5e2e7ca8e71: 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:37589){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(10)@c5e2e7ca8e71: 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 [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)@c5e2e7ca8e71: Final verdict of PTC: none MTC@c5e2e7ca8e71: Setting final verdict of the test case. MTC@c5e2e7ca8e71: Local verdict of MTC: pass MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass) MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass) MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass) MTC@c5e2e7ca8e71: Test case TC_srv_reconnect finished. Verdict: pass MTC@c5e2e7ca8e71: 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'. Sat Apr 5 13:38:54 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@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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 ------ Sat Apr 5 13:38:56 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) RSPRO_SRV0(12)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(12)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@c5e2e7ca8e71: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(12)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(12)@c5e2e7ca8e71: 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@c5e2e7ca8e71: setverdict(pass): none -> pass RSPRO_SRV1(13)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) RSPRO_SRV1(13)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(13)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(13)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ 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:36325){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)@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) RSPRO_SRV1(14)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(14)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@c5e2e7ca8e71: setverdict(pass): pass -> pass, component reason not changed RSPRO_SRV1(14)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(14)@c5e2e7ca8e71: 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:32811){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:38861){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)@c5e2e7ca8e71: Final verdict of PTC: none RSPRO_SRV0(12)@c5e2e7ca8e71: Final verdict of PTC: none MTC@c5e2e7ca8e71: Setting final verdict of the test case. MTC@c5e2e7ca8e71: Local verdict of MTC: 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@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass) MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass) MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV1(14): none (pass -> pass) MTC@c5e2e7ca8e71: Test case TC_bank_reconnect finished. Verdict: 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 MTC@c5e2e7ca8e71: 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'. Sat Apr 5 13:39:15 UTC 2025 [1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7036) Waiting for packet dumper to finish... 1 (prev_count=7036, count=8028) MTC@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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 ------ Sat Apr 5 13:39:17 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) RSPRO_SRV0(15)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(15)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@c5e2e7ca8e71: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(15)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(15)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: Established a new IPA connection (conn_id=2) MTC@c5e2e7ca8e71: setverdict(pass): none -> pass RSPRO_SRV1(16)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(16)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(16)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: 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)@c5e2e7ca8e71: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(16)@c5e2e7ca8e71: 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 Aborted RSPRO_SRV1(16)@c5e2e7ca8e71: IPA: Closed RSPRO_SRV0(15)@c5e2e7ca8e71: IPA: Closed RSPRO_SRV1(16)@c5e2e7ca8e71: Final verdict of PTC: none MTC@c5e2e7ca8e71: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO" RSPRO_SRV0(15)@c5e2e7ca8e71: Final verdict of PTC: none MTC@c5e2e7ca8e71: Setting final verdict of the test case. MTC@c5e2e7ca8e71: Local verdict of MTC: fail reason: "Received unexpected != RPSRO" MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail) MTC@c5e2e7ca8e71: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail) MTC@c5e2e7ca8e71: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO MTC@c5e2e7ca8e71: 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'. Sat Apr 5 13:39:26 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@c5e2e7ca8e71: 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@c5e2e7ca8e71: 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 ------ Sat Apr 5 13:39:28 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1467/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-20250405-1334-33bd2ba3-1'][0m testenv-remsim-all-osmocom-latest-20250405-1334-33bd2ba3-1 [1;34m[testenv][client] Stopping testsuite (2051865)[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/1467/artifa... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
See https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/display/redirect
Changes:
------------------------------------------ [...truncated 546.59 KiB...] [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 Sun Apr 6 13:38:40 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@fd8d3412b174: 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@fd8d3412b174: 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 ------ Sun Apr 6 13:38:42 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@fd8d3412b174: 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@fd8d3412b174: Test case TC_srv_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: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(9)@fd8d3412b174: Established a new IPA connection (conn_id=2) RSPRO_SRV0(9)@fd8d3412b174: 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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(9)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@fd8d3412b174: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(9)@fd8d3412b174: 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)@fd8d3412b174: 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)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(9)@fd8d3412b174: 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:39553){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)@fd8d3412b174: Final verdict of PTC: none MTC@fd8d3412b174: 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)@fd8d3412b174: Established a new IPA connection (conn_id=2) RSPRO_SRV0(11)@fd8d3412b174: 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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(11)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(11)@fd8d3412b174: 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@fd8d3412b174: setverdict(pass): none -> pass RSPRO_SRV0(11)@fd8d3412b174: 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)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(11)@fd8d3412b174: 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:38715){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)@fd8d3412b174: Final verdict of PTC: none RSPRO_SRV1(10)@fd8d3412b174: Final verdict of PTC: none MTC@fd8d3412b174: Setting final verdict of the test case. MTC@fd8d3412b174: Local verdict of MTC: 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@fd8d3412b174: Local verdict of PTC RSPRO_SRV0(9): 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@fd8d3412b174: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass) MTC@fd8d3412b174: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass) MTC@fd8d3412b174: Test case TC_srv_reconnect finished. Verdict: pass MTC@fd8d3412b174: 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'. Sun Apr 6 13:38:52 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@fd8d3412b174: 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@fd8d3412b174: 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 ------ Sun Apr 6 13:38:54 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@fd8d3412b174: 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@fd8d3412b174: 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)@fd8d3412b174: Established a new IPA connection (conn_id=2) RSPRO_SRV0(12)@fd8d3412b174: 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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(12)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@fd8d3412b174: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(12)@fd8d3412b174: 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)@fd8d3412b174: 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)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(12)@fd8d3412b174: 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@fd8d3412b174: setverdict(pass): none -> pass RSPRO_SRV1(13)@fd8d3412b174: Established a new IPA connection (conn_id=2) RSPRO_SRV1(13)@fd8d3412b174: 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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(13)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(13)@fd8d3412b174: 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)@fd8d3412b174: 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)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(13)@fd8d3412b174: 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:39739){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)@fd8d3412b174: 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@fd8d3412b174: 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)@fd8d3412b174: Established a new IPA connection (conn_id=2) RSPRO_SRV1(14)@fd8d3412b174: 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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(14)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(14)@fd8d3412b174: 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)@fd8d3412b174: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MTC@fd8d3412b174: setverdict(pass): pass -> pass, component reason not changed RSPRO_SRV1(14)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(14)@fd8d3412b174: 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:41971){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:46115){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)@fd8d3412b174: Final verdict of PTC: none RSPRO_SRV0(12)@fd8d3412b174: Final verdict of PTC: none MTC@fd8d3412b174: Setting final verdict of the test case. MTC@fd8d3412b174: Local verdict of MTC: pass MTC@fd8d3412b174: Local verdict of PTC RSPRO_SRV0(12): none (pass -> pass) MTC@fd8d3412b174: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass) MTC@fd8d3412b174: 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(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 MTC@fd8d3412b174: Test case TC_bank_reconnect finished. Verdict: pass MTC@fd8d3412b174: 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'. Sun Apr 6 13:39:13 UTC 2025 [1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7336) Waiting for packet dumper to finish... 1 (prev_count=7336, count=8328) MTC@fd8d3412b174: 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@fd8d3412b174: 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 ------ Sun Apr 6 13:39:15 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@fd8d3412b174: 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@fd8d3412b174: 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 RSPRO_SRV0(15)@fd8d3412b174: Established a new IPA connection (conn_id=2) RSPRO_SRV0(15)@fd8d3412b174: 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;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 [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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(15)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@fd8d3412b174: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(15)@fd8d3412b174: 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)@fd8d3412b174: 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)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(15)@fd8d3412b174: 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)@fd8d3412b174: Established a new IPA connection (conn_id=2) MTC@fd8d3412b174: setverdict(pass): none -> pass RSPRO_SRV1(16)@fd8d3412b174: 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)@fd8d3412b174: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(16)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(16)@fd8d3412b174: 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)@fd8d3412b174: 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)@fd8d3412b174: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(16)@fd8d3412b174: 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)@fd8d3412b174: IPA: Closed Aborted RSPRO_SRV1(16)@fd8d3412b174: Final verdict of PTC: none RSPRO_SRV0(15)@fd8d3412b174: IPA: Closed MTC@fd8d3412b174: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO" RSPRO_SRV0(15)@fd8d3412b174: Final verdict of PTC: none MTC@fd8d3412b174: Setting final verdict of the test case. MTC@fd8d3412b174: Local verdict of MTC: fail reason: "Received unexpected != RPSRO" MTC@fd8d3412b174: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail) MTC@fd8d3412b174: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail) MTC@fd8d3412b174: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO MTC@fd8d3412b174: 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'. Sun Apr 6 13:39:24 UTC 2025 [1;31m------ RemsimClient_Tests.TC_bank_disconnect fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=5232) [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=5232, count=5968) MTC@fd8d3412b174: 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@fd8d3412b174: 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 ------ Sun Apr 6 13:39:26 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1468/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-20250406-1334-6b810880-1'][0m testenv-remsim-all-osmocom-latest-20250406-1334-6b810880-1 [1;34m[testenv][client] Stopping testsuite (2446566)[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/1468/artifa... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
See https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/display/redirect?page=changes
Changes:
[Oliver Smith] testenv: fix OSMO_DEV_MAKE_DIR env var
[Oliver Smith] sccp: testenv: fix hardcoded sccp_demo_user path
[Oliver Smith] sccp: testenv: fix run with asan + latest binaries
[Oliver Smith] testenv: remove TESTENV_GIT_DIR
------------------------------------------ [...truncated 543.92 KiB...] MTC@7cf629243cb0: Setting final verdict of the test case. MTC@7cf629243cb0: Local verdict of MTC: pass MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV0(7): none (pass -> pass) MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV1(8): none (pass -> pass) MTC@7cf629243cb0: Test case TC_srv_connectClient_configClientBank finished. Verdict: pass MTC@7cf629243cb0: 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'. Mon Apr 7 13:38:41 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@7cf629243cb0: 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@7cf629243cb0: 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 ------ Mon Apr 7 13:38:43 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@7cf629243cb0: 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@7cf629243cb0: 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)@7cf629243cb0: Established a new IPA connection (conn_id=2) RSPRO_SRV0(9)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(9)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@7cf629243cb0: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(9)@7cf629243cb0: 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)@7cf629243cb0: 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)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(9)@7cf629243cb0: 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:40365){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)@7cf629243cb0: Final verdict of PTC: none MTC@7cf629243cb0: 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)@7cf629243cb0: Established a new IPA connection (conn_id=2) RSPRO_SRV0(11)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(11)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@7cf629243cb0: setverdict(pass): none -> pass RSPRO_SRV0(11)@7cf629243cb0: 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)@7cf629243cb0: 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)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(11)@7cf629243cb0: 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:43127){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)@7cf629243cb0: Final verdict of PTC: none RSPRO_SRV1(10)@7cf629243cb0: Final verdict of PTC: none MTC@7cf629243cb0: Setting final verdict of the test case. MTC@7cf629243cb0: Local verdict of MTC: pass MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass) MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass) MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass) MTC@7cf629243cb0: Test case TC_srv_reconnect finished. Verdict: pass MTC@7cf629243cb0: 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'. [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 Mon Apr 7 13:38:53 UTC 2025 [1;32m====== RemsimClient_Tests.TC_srv_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=5140) Waiting for packet dumper to finish... 1 (prev_count=5140, count=5636) MTC@7cf629243cb0: 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@7cf629243cb0: 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 ------ Mon Apr 7 13:38:55 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@7cf629243cb0: 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@7cf629243cb0: 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)@7cf629243cb0: Established a new IPA connection (conn_id=2) RSPRO_SRV0(12)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(12)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(12)@7cf629243cb0: 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)@7cf629243cb0: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MTC@7cf629243cb0: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(12)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(12)@7cf629243cb0: 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@7cf629243cb0: setverdict(pass): none -> pass RSPRO_SRV1(13)@7cf629243cb0: Established a new IPA connection (conn_id=2) RSPRO_SRV1(13)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(13)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(13)@7cf629243cb0: 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)@7cf629243cb0: 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)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(13)@7cf629243cb0: 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:34871){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)@7cf629243cb0: 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@7cf629243cb0: 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)@7cf629243cb0: Established a new IPA connection (conn_id=2) RSPRO_SRV1(14)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(14)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(14)@7cf629243cb0: 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)@7cf629243cb0: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MTC@7cf629243cb0: setverdict(pass): pass -> pass, component reason not changed RSPRO_SRV1(14)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(14)@7cf629243cb0: 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:35549){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:37517){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)@7cf629243cb0: Final verdict of PTC: none RSPRO_SRV1(14)@7cf629243cb0: Final verdict of PTC: none MTC@7cf629243cb0: Setting final verdict of the test case. MTC@7cf629243cb0: Local verdict of MTC: pass MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV0(12): 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@7cf629243cb0: Local verdict of PTC RSPRO_SRV1(13): none (pass -> pass) MTC@7cf629243cb0: 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(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@7cf629243cb0: Test case TC_bank_reconnect finished. Verdict: pass MTC@7cf629243cb0: 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'. Mon Apr 7 13:39:14 UTC 2025 [1;32m====== RemsimClient_Tests.TC_bank_reconnect pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=7036) Waiting for packet dumper to finish... 1 (prev_count=7036, count=8028) MTC@7cf629243cb0: 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@7cf629243cb0: 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 ------ Mon Apr 7 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/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@7cf629243cb0: 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@7cf629243cb0: 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)@7cf629243cb0: Established a new IPA connection (conn_id=2) RSPRO_SRV0(15)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(15)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@7cf629243cb0: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(15)@7cf629243cb0: 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)@7cf629243cb0: 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)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(15)@7cf629243cb0: 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)@7cf629243cb0: Established a new IPA connection (conn_id=2) MTC@7cf629243cb0: setverdict(pass): none -> pass RSPRO_SRV1(16)@7cf629243cb0: 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)@7cf629243cb0: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(16)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(16)@7cf629243cb0: 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)@7cf629243cb0: 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)@7cf629243cb0: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(16)@7cf629243cb0: 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 Aborted RSPRO_SRV1(16)@7cf629243cb0: IPA: Closed RSPRO_SRV0(15)@7cf629243cb0: IPA: Closed MTC@7cf629243cb0: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO" RSPRO_SRV1(16)@7cf629243cb0: Final verdict of PTC: none RSPRO_SRV0(15)@7cf629243cb0: Final verdict of PTC: none MTC@7cf629243cb0: Setting final verdict of the test case. MTC@7cf629243cb0: Local verdict of MTC: fail reason: "Received unexpected != RPSRO" MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail) MTC@7cf629243cb0: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail) MTC@7cf629243cb0: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO MTC@7cf629243cb0: 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'. Mon Apr 7 13:39:25 UTC 2025 [1;31m------ RemsimClient_Tests.TC_bank_disconnect fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4768) [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=4768, count=5504) MTC@7cf629243cb0: 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@7cf629243cb0: 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 ------ Mon Apr 7 13:39:27 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test-latest/1469/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-20250407-1334-a165183b-1'][0m testenv-remsim-all-osmocom-latest-20250407-1334-a165183b-1 [1;34m[testenv][client] Stopping testsuite (1017917)[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/1469/artifa... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
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/artifa... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
jenkins-notifications@lists.osmocom.org