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

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/display/redirect> Changes: ------------------------------------------ [...truncated 547.07 KiB...] 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 Apr 4 10:17:40 UTC 2025 ====== RemsimClient_Tests.TC_srv_connectClient_configClientBank pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=4736) Waiting for packet dumper to finish... 1 (prev_count=4736, count=5728) MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_srv_connectClient_configClientBank pass' was executed successfully (exit status: 0). MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> RemsimClient_Tests.TC_srv_reconnect'. ------ RemsimClient_Tests.TC_srv_reconnect ------ Fri Apr 4 10:17:42 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_srv_reconnect.pcap.stdout> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> RemsimClient_Tests.TC_srv_reconnect' was executed successfully (exit status: 0). MTC@1aaa98b1c759: Test case TC_srv_reconnect 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(9)@1aaa98b1c759: Established a new IPA connection (conn_id=2) RSPRO_SRV0(9)@1aaa98b1c759: 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(9)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(9)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(9)@1aaa98b1c759: 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)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(9)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@1aaa98b1c759: 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){WAIT_BANKD}: Event MF_E_SRVC_CONNECTED not permitted DLINP NOTICE stream_cli.c:547 CLICONN(server,r=127.0.0.1:9998<->l=127.0.0.1:39271){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(9)@1aaa98b1c759: Final verdict of PTC: none MTC@1aaa98b1c759: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). 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(11)@1aaa98b1c759: Established a new IPA connection (conn_id=2) RSPRO_SRV0(11)@1aaa98b1c759: 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(11)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(11)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MTC@1aaa98b1c759: setverdict(pass): none -> pass RSPRO_SRV0(11)@1aaa98b1c759: 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)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(11)@1aaa98b1c759: 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 DLINP NOTICE stream_cli.c:547 CLICONN(server,r=127.0.0.1:9998<->l=127.0.0.1:41275){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(11)@1aaa98b1c759: Final verdict of PTC: none RSPRO_SRV1(10)@1aaa98b1c759: Final verdict of PTC: none MTC@1aaa98b1c759: Setting final verdict of the test case. MTC@1aaa98b1c759: Local verdict of MTC: pass MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV0(9): none (pass -> pass) MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV1(10): none (pass -> pass) MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV0(11): none (pass -> pass) MTC@1aaa98b1c759: Test case TC_srv_reconnect finished. Verdict: pass MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_srv_reconnect pass'. 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(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 Apr 4 10:17:52 UTC 2025 ====== RemsimClient_Tests.TC_srv_reconnect pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=5164) Waiting for packet dumper to finish... 1 (prev_count=5164, count=5660) MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_srv_reconnect pass' was executed successfully (exit status: 0). MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> RemsimClient_Tests.TC_bank_reconnect'. ------ RemsimClient_Tests.TC_bank_reconnect ------ Fri Apr 4 10:17:54 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_reconnect.pcap.stdout> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> RemsimClient_Tests.TC_bank_reconnect' was executed successfully (exit status: 0). MTC@1aaa98b1c759: Test case TC_bank_reconnect started. 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 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 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(12)@1aaa98b1c759: Established a new IPA connection (conn_id=2) RSPRO_SRV0(12)@1aaa98b1c759: 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(12)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(12)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(12)@1aaa98b1c759: 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@1aaa98b1c759: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(12)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV0(12)@1aaa98b1c759: 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 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 MTC@1aaa98b1c759: setverdict(pass): none -> pass RSPRO_SRV1(13)@1aaa98b1c759: Established a new IPA connection (conn_id=2) RSPRO_SRV1(13)@1aaa98b1c759: 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(13)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(13)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(13)@1aaa98b1c759: 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)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(13)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } DLINP NOTICE stream_cli.c:547 CLICONN(bankd,r=127.0.0.1:9999<->l=127.0.0.1:33263){CONNECTED} connection closed with srv DRSPRO NOTICE ../rspro_client_fsm.c:154 RSPRO_CLIENT(bankd){ESTABLISHED}: RSPRO link to 127.0.0.1:9999 DOWN DRSPRO INFO ../rspro_client_fsm.c:428 RSPRO_CLIENT(bankd){REESTABLISH_DELAY}: Destroying existing connection to server RSPRO_SRV1(13)@1aaa98b1c759: Final verdict of PTC: none 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(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@1aaa98b1c759: 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)@1aaa98b1c759: Established a new IPA connection (conn_id=2) RSPRO_SRV1(14)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(14)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(14)@1aaa98b1c759: 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)@1aaa98b1c759: IPA ID RESP: { { len := 19, tag := IPAC_IDTAG_UNITNAME (1), data := '30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MTC@1aaa98b1c759: setverdict(pass): pass -> pass, component reason not changed RSPRO_SRV1(14)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(14)@1aaa98b1c759: 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:35247){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:40267){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)@1aaa98b1c759: Final verdict of PTC: none RSPRO_SRV0(12)@1aaa98b1c759: Final verdict of PTC: none MTC@1aaa98b1c759: Setting final verdict of the test case. MTC@1aaa98b1c759: Local verdict of MTC: pass MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV0(12): 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 DRSPRO INFO ../rspro_client_fsm.c:454 RSPRO_CLIENT(bankd){REESTABLISH}: Creating TCP connection to server at 127.0.0.1:9999 MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV1(13): 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 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@1aaa98b1c759: Local verdict of PTC RSPRO_SRV1(14): none (pass -> pass) MTC@1aaa98b1c759: Test case TC_bank_reconnect finished. Verdict: pass MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_bank_reconnect pass'. Fri Apr 4 10:18:13 UTC 2025 ====== RemsimClient_Tests.TC_bank_reconnect pass ====== Waiting for packet dumper to finish... 0 (prev_count=-1, count=7372) Waiting for packet dumper to finish... 1 (prev_count=7372, count=8364) MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_bank_reconnect pass' was executed successfully (exit status: 0). MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> RemsimClient_Tests.TC_bank_disconnect'. ------ RemsimClient_Tests.TC_bank_disconnect ------ Fri Apr 4 10:18:15 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect.pcap.stdout> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> RemsimClient_Tests.TC_bank_disconnect' was executed successfully (exit status: 0). MTC@1aaa98b1c759: 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)@1aaa98b1c759: Established a new IPA connection (conn_id=2) RSPRO_SRV0(15)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV0(15)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV0(15)@1aaa98b1c759: 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)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@1aaa98b1c759: Warning: Re-starting timer g_rspro_srv_Tguard, which is already active (running or expired). RSPRO_SRV0(15)@1aaa98b1c759: 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 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)@1aaa98b1c759: Established a new IPA connection (conn_id=2) MTC@1aaa98b1c759: setverdict(pass): none -> pass RSPRO_SRV1(16)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } RSPRO_SRV1(16)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } RSPRO_SRV1(16)@1aaa98b1c759: 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)@1aaa98b1c759: 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)@1aaa98b1c759: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } RSPRO_SRV1(16)@1aaa98b1c759: 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 DRSPRO INFO ../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)@1aaa98b1c759: IPA: Closed RSPRO_SRV0(15)@1aaa98b1c759: IPA: Closed RSPRO_SRV1(16)@1aaa98b1c759: Final verdict of PTC: none RSPRO_SRV0(15)@1aaa98b1c759: Final verdict of PTC: none MTC@1aaa98b1c759: setverdict(fail): pass -> fail reason: "Received unexpected != RPSRO", new component reason: "Received unexpected != RPSRO" MTC@1aaa98b1c759: Setting final verdict of the test case. MTC@1aaa98b1c759: Local verdict of MTC: fail reason: "Received unexpected != RPSRO" MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV0(15): none (fail -> fail) MTC@1aaa98b1c759: Local verdict of PTC RSPRO_SRV1(16): none (fail -> fail) MTC@1aaa98b1c759: Test case TC_bank_disconnect finished. Verdict: fail reason: Received unexpected != RPSRO MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_bank_disconnect fail'. Fri Apr 4 10:18:24 UTC 2025 ------ RemsimClient_Tests.TC_bank_disconnect fail ------ Waiting for packet dumper to finish... 0 (prev_count=-1, count=4792) [testenv][client] Looking for a coredump [testenv][client] coredumpctl is not available, won't try to get coredump [testenv][client] client unexpected exit during RemsimClient_Tests.TC_bank_disconnect! rc=0 [testenv][client] Waiting until test has stopped... Waiting for packet dumper to finish... 1 (prev_count=4792, count=5528) MTC@1aaa98b1c759: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> RemsimClient_Tests.TC_bank_disconnect fail' was executed successfully (exit status: 0). MTC@1aaa98b1c759: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/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 10:18:26 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/logs/client/testsuite/RemsimClient_Tests.TC_bank_disconnect_reconnect.pcap.stdout> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [testenv][client] Stopping podman container [testenv][client] + ['podman', 'kill', 'testenv-remsim-all-osmocom-nightly-20250404-1013-c250308f-1'] testenv-remsim-all-osmocom-nightly-20250404-1013-c250308f-1 [testenv][client] Stopping testsuite (3399613) [testenv] feed_watchdog_loop: podman container has stopped [testenv][client] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-remsim-test/1712/artifact/log…  Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.