Build failed in Jenkins: ttcn3-remsim-test #1754

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