See https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/display/redirect

Changes:

------------------------------------------ [...truncated 22.38 MiB...] 20251223153146441 DLM3UA DEBUG 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153146441 DLM3UA DEBUG 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153146441 DLM3UA DEBUG 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632) 20251223153146441 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49) 20251223153146441 DLSS7 DEBUG 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275) 20251223153146441 DLSS7 DEBUG 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80) 20251223153146441 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90) 20251223153146442 DLSS7 DEBUG XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153146442 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 b9 00 00 00 bb 03 00 00 00 04 00 00 85 b1 18 9f 00 00 00 00 00 (ss7_asp.c:1400) 20251223153146442 DLSS7 DEBUG 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153146442 DLM3UA DEBUG 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153146442 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153146443 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=13 (m3ua.c:632) 20251223153146443 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49) 20251223153146443 DLSS7 DEBUG 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=13 -> eSLS=45: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275) 20251223153146443 DLSS7 DEBUG 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80) 20251223153146443 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90) 20251223153146443 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153146443 DLSS7 DEBUG 0: ASP(virt-msc0-0) Tx 40 bytes: [L2]> 01 00 01 01 00 00 00 28 02 10 00 17 00 00 00 bb 00 00 00 b9 03 00 00 0d 05 b1 18 9f 00 00 85 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400) 20251223153146443 DLSS7 DEBUG 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153146443 DLM3UA DEBUG 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153146443 DLM3UA DEBUG 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153146443 DLM3UA DEBUG 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632) 20251223153146443 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49) 20251223153146443 DLSS7 DEBUG 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275) 20251223153146443 DLSS7 DEBUG 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80) 20251223153146443 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90) 20251223153146443 DLSS7 DEBUG XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153146443 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 40 bytes: [L2]> 01 00 01 01 00 00 00 28 02 10 00 1d 00 00 00 b9 00 00 00 bb 03 00 00 00 06 00 00 86 00 01 06 00 04 20 04 01 00 00 00 00 (ss7_asp.c:1400) VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on local reference:1 VirtMSC-SCCP(2258)@a27f6ca23576: RLC received in state:disconnect_pending 20251223153146444 DLSS7 DEBUG 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153146444 DLM3UA DEBUG 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153146444 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153146444 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=14 (m3ua.c:632) 20251223153146444 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49) 20251223153146444 DLSS7 DEBUG 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=14 -> eSLS=46: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275) 20251223153146444 DLSS7 DEBUG 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80) 20251223153146444 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90) 20251223153146444 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153146444 DLSS7 DEBUG 0: ASP(virt-msc0-0) Tx 44 bytes: [L2]> 01 00 01 01 00 00 00 2c 02 10 00 1a 00 00 00 bb 00 00 00 b9 03 00 00 0e 06 8a a3 76 00 01 03 00 01 21 00 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400) VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on local reference:2 VirtMSC-SCCP(2258)@a27f6ca23576: DT1 will be put to the reassembly buffer VirtMSC-SCCP(2258)@a27f6ca23576: DT1 data has been put to the reassembly buffer VirtMSC-SCCP(2258)@a27f6ca23576: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes 20251223153146894 DLSS7 DEBUG 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153146894 DLM3UA DEBUG 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153146894 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153146894 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=2=0.0.2 sls=8 (m3ua.c:632) 20251223153146894 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing (mtp3_hmdc.c:49) 20251223153146894 DLSS7 INFO 0: CombinedLinkset(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=2=0.0.2,SLS=8 -> eSLS=104: Normal Route via 'virt-msc1' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322) 20251223153146894 DLSS7 ERROR 0: MTP-TRANSFER.req for dpc=2=0.0.2: no route! (mtp3_hmrt.c:113) 20251223153146894 DLSS7 DEBUG 0: T8: SP 2=0.0.2: Start (ss7_instance.c:879) 20251223153146894 DLSS7 DEBUG 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=2=0.0.2,DPC=187=0.23.3,SLS=8 -> eSLS=104: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275) 20251223153146894 DLSS7 INFO 0: Message received for inaccessible SP 2=0.0.2. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120) 20251223153146894 DLSS7 DEBUG XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153146894 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 02 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400) 20251223153146894 DLM3UA NOTICE 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.2/0 (xua_snm.c:501) 20251223153146894 DLSS7 NOTICE 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409) 20251223153146895 DLCS ERROR Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) 20251223153146896 DLSS7 DEBUG 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153146896 DLM3UA DEBUG 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153146897 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153146897 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=3=0.0.3 sls=6 (m3ua.c:632) 20251223153146897 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=3=0.0.3 not local, message is for routing (mtp3_hmdc.c:49) 20251223153146897 DLSS7 INFO 0: CombinedLinkset(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=3=0.0.3,SLS=6 -> eSLS=118: Normal Route via 'virt-msc2' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322) 20251223153146897 DLSS7 ERROR 0: MTP-TRANSFER.req for dpc=3=0.0.3: no route! (mtp3_hmrt.c:113) 20251223153146897 DLSS7 DEBUG 0: T8: SP 3=0.0.3: Start (ss7_instance.c:879) 20251223153146897 DLSS7 DEBUG 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=3=0.0.3,DPC=187=0.23.3,SLS=6 -> eSLS=118: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275) 20251223153146897 DLSS7 INFO 0: Message received for inaccessible SP 3=0.0.3. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120) 20251223153146897 DLSS7 DEBUG XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153146897 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 03 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400) 20251223153146897 DLM3UA NOTICE 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.3/0 (xua_snm.c:501) 20251223153146897 DLSS7 NOTICE 0: RT(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409) 20251223153146897 DLCS ERROR Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) 20251223153147895 DLSS7 DEBUG 0: T8: SP 2=0.0.2: Timeout (ss7_instance.c:858) 20251223153147897 DLSS7 DEBUG 0: T8: SP 3=0.0.3: Timeout (ss7_instance.c:858) VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on connection ID:2 20251223153148452 DLSS7 DEBUG 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153148452 DLM3UA DEBUG 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153148452 DLM3UA DEBUG 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153148452 DLM3UA DEBUG 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632) 20251223153148452 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49) 20251223153148452 DLSS7 DEBUG 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275) 20251223153148452 DLSS7 DEBUG 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80) 20251223153148452 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90) 20251223153148452 DLSS7 DEBUG XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153148452 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 b9 00 00 00 bb 03 00 00 00 04 00 00 86 8a a3 76 00 00 00 00 00 (ss7_asp.c:1400) 20251223153148453 DLSS7 DEBUG 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153148453 DLM3UA DEBUG 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811) 20251223153148453 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601) 20251223153148453 DLM3UA DEBUG 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=14 (m3ua.c:632) 20251223153148453 DLSS7 DEBUG 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49) 20251223153148453 DLSS7 DEBUG 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=14 -> eSLS=46: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275) 20251223153148453 DLSS7 DEBUG 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80) 20251223153148453 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90) 20251223153148453 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563) 20251223153148453 DLSS7 DEBUG 0: ASP(virt-msc0-0) Tx 40 bytes: [L2]> 01 00 01 01 00 00 00 28 02 10 00 17 00 00 00 bb 00 00 00 b9 03 00 00 0e 05 8a a3 76 00 00 86 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400) VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on local reference:2 VirtMSC-SCCP(2258)@a27f6ca23576: RLC received in state:disconnect_pending MTC@a27f6ca23576: talloc reports "struct bsc_subscr" x 0, expecting 0 MTC@a27f6ca23576: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 VirtMSC-STATS(2257)@a27f6ca23576: Final verdict of PTC: none IPA-CTRL-CLI-IPA(2262)@a27f6ca23576: Final verdict of PTC: none MTC@a27f6ca23576: setverdict(pass): none -> pass 20251223153148469 DLINP NOTICE virt-msc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:360) 20251223153148469 DLSS7 DEBUG 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107) 20251223153148469 DLSS7 INFO 0: ASP(virt-msc0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998) 20251223153148469 DLSS7 NOTICE 0: ASP(virt-msc0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1021) 20251223153148469 DLINP NOTICE virt-msc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:360) 20251223153148469 DLSS7 DEBUG 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107) 20251223153148469 DLSS7 INFO 0: ASP(virt-msc0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998) 20251223153148469 DLSS7 NOTICE 0: ASP(virt-msc0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006) 20251223153148469 DLINP NOTICE SRVCONN(virt-msc0-0,r=127.0.0.1:23905<->l=127.0.0.1:2905) connection closed with client (stream_srv.c:829) 20251223153148469 DLSS7 DEBUG 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153148469 DLSS7 INFO virt-msc0-0: connection closed (ss7_asp.c:1349) 20251223153148469 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355) 20251223153148469 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859) 20251223153148469 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429) 20251223153148469 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405) 20251223153148469 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592) 20251223153148469 DLSS7 DEBUG XUA_AS(virt-msc0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116) 20251223153148469 DLSS7 NOTICE 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409) 20251223153148469 DLSS7 NOTICE 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409) 20251223153148470 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400) VirtSMLC-SCCP(2260)@a27f6ca23576: Final verdict of PTC: none VirtMSC-M3UA(2259)@a27f6ca23576: Final verdict of PTC: none VirtMSC-SCCP(2258)@a27f6ca23576: Final verdict of PTC: none 20251223153148470 DLSS7 DEBUG 0: ASP(virt-smlc-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 06 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129) 20251223153148470 DLINP NOTICE virt-smlc-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:360) 20251223153148470 DLSS7 DEBUG 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107) 20251223153148470 DLSS7 INFO 0: ASP(virt-smlc-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998) 20251223153148470 DLSS7 NOTICE 0: ASP(virt-smlc-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1021) 20251223153148470 DLINP ERROR SRVCONN(virt-smlc-0,r=127.0.0.1:23908<->l=127.0.0.1:2905) error to send: Bad file descriptor (stream_srv.c:803) 20251223153148470 DLINP NOTICE SRVCONN(ts-0-2-rsl,r=127.0.0.1:10000<->l=127.0.0.1:3003) connection closed with client (stream_srv.c:777) 20251223153148470 DLINP NOTICE E1TS(0:2) failed reading from socket: 0 (ipaccess.c:554) 20251223153148470 DLINP NOTICE E1L(0) Signalling link down (bts_ipaccess_nanobts.c:778) 20251223153148470 DLINP NOTICE (bts=0) Dropping OML link: link down (bts_ipaccess_nanobts.c:543) 20251223153148470 DLMI ERROR (bts=0,trx=0) Lost E1 OML link (osmo_bsc_main.c:487) 20251223153148470 DLINP NOTICE virt-smlc-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:360) 20251223153148470 DLSS7 DEBUG 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107) 20251223153148470 DLSS7 INFO 0: ASP(virt-smlc-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998) 20251223153148470 DLSS7 NOTICE 0: ASP(virt-smlc-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006) 20251223153148470 DLINP NOTICE SRVCONN(virt-smlc-0,r=127.0.0.1:23908<->l=127.0.0.1:2905) connection closed with client (stream_srv.c:829) 20251223153148470 DLSS7 DEBUG 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107) 20251223153148470 DLSS7 INFO virt-smlc-0: connection closed (ss7_asp.c:1349) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429) 20251223153148470 DLSS7 DEBUG XUA_AS(virt-smlc){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405) 20251223153148470 DLSS7 DEBUG XUA_AS(virt-smlc){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592) 20251223153148470 DLSS7 DEBUG XUA_AS(virt-smlc){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116) 20251223153148470 DLSS7 NOTICE 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=virt-smlc,st=available) changed to status 'unavailable' (ss7_route.c:409) 20251223153148470 DLSS7 NOTICE 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=virt-smlc,st=available) changed to status 'unavailable' (ss7_route.c:409) 20251223153148470 DLSS7 DEBUG 0: ASP(bsc0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 be (ss7_asp.c:1400) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129) 20251223153148470 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129) VirtSMLC-M3UA(2261)@a27f6ca23576: Final verdict of PTC: none VirtMGW-MGCP-0(2263)@a27f6ca23576: Final verdict of PTC: none 20251223153148472 DLINP NOTICE E1L(0) Signalling link down (bts_ipaccess_nanobts.c:778) 20251223153148472 DLMI ERROR (bts=0,trx=0) Lost E1 OSMO link (osmo_bsc_main.c:487) 20251223153148472 DLINP NOTICE (bts=0,trx=0) Dropping RSL link: OML link drop (bts_ipaccess_nanobts.c:514) 20251223153148472 DLMI ERROR (bts=0,trx=0) Lost E1 RSL link (osmo_bsc_main.c:487) DLINP stream_cli.c:548 CLICONN(ts-0-1-oml,r=127.0.0.1:3002<->l=127.0.0.1:45913){CONNECTED} connection closed with srv DABIS abis.c:429 E1L(0) Signalling link down DOML bts_shutdown_fsm.c:268 BTS_SHUTDOWN(bts0)[0x55f392214990]{NONE}: Shutting down BTS, exit 1, reason: Abis close DLINP input/ipaccess.c:926 E1TS(0:1) failed reading from socket: 0 IPA-BTS0-TRX0-RSL-IPA(2264)@a27f6ca23576: Final verdict of PTC: none MTC@a27f6ca23576: Setting final verdict of the test case. 20251223153148473 DLM3UA NOTICE 0: ASP(asp-clnt-msc-0) Rx DUNA() for 0.23.1/0 (xua_snm.c:501) 20251223153148473 DLSS7 NOTICE 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=available) changed to status 'unavailable' (ss7_route.c:409) 20251223153148474 DMSC NOTICE (msc=0) now unreachable: N-PCSTATE ind: pc=185=0.23.1 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (osmo_bsc_sigtran.c:311) 20251223153148474 DRESET NOTICE bssmap_reset(msc-0)[0x557077126dd0]{DISCONNECTED}: link lost (bssmap_reset.c:91) 20251223153148474 DMSC NOTICE (msc=0) BSSMAP association is down (a_reset.c:54) 20251223153148474 DLCS ERROR Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) 20251223153148474 DLM3UA NOTICE 0: ASP(asp-clnt-msc-0) Rx DUNA() for 0.23.6/0 (xua_snm.c:501) 20251223153148474 DLSS7 NOTICE 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=available) changed to status 'unavailable' (ss7_route.c:409) 20251223153148474 DLCS ERROR Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) MTC@a27f6ca23576: Local verdict of MTC: pass MTC@a27f6ca23576: Local verdict of PTC VirtMSC-STATS(2257): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtMSC-SCCP(2258): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtMSC-M3UA(2259): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtSMLC-SCCP(2260): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtSMLC-M3UA(2261): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC IPA-CTRL-CLI-IPA(2262): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtMGW-MGCP-0(2263): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(2264): none (pass -> pass) MTC@a27f6ca23576: Test case TC_dyn_ts_sdcch8_act_deact finished. Verdict: pass MTC@a27f6ca23576: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/ttcn3-tcpdump-stop.sh BSC_Tests.TC_dyn_ts_sdcch8_act_deact pass'. Tue Dec 23 15:31:48 UTC 2025 ====== BSC_Tests.TC_dyn_ts_sdcch8_act_deact pass ======

Saving talloc report from 127.0.0.1:4242 to BSC_Tests.TC_dyn_ts_sdcch8_act_deact.talloc [testenv][generic] Timeout in wait_until_test_stopped() 20251223153150471 DLSS7 DEBUG XUA_AS(virt-smlc){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349) 20251223153150471 DLSS7 DEBUG XUA_AS(virt-smlc){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665) 20251223153150471 DLSS7 DEBUG XUA_AS(virt-smlc){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675) 20251223153150471 DLSS7 DEBUG XUA_AS(virt-msc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349) 20251223153150471 DLSS7 DEBUG XUA_AS(virt-msc0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665) 20251223153150471 DLSS7 DEBUG XUA_AS(virt-msc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675) [testenv][generic] Running gdb to get a backtrace [testenv][generic] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/data/scripts/qemu:https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/bsc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-bsc-generic-osmocom-nightly-20251223-1438-1450a117-0', 'sh', '-c', 'echo; gdb --batch /usr/bin/osmo-bts-omldummy https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifact/logs/bts-1/core -ex bt | tee https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifact/logs/bts-1/core.backtrace; echo']

[New LWP 23711] [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `/usr/bin/osmo-bts-omldummy -fCCN,EGPRS,GPRS,IPv6_NSVC,PAGING_COORDINATION,OSMUX 127.0.0.1 1235 1'. Program terminated with signal SIGABRT, Aborted.

warning: 44 ./nptl/pthread_kill.c: No such file or directory #0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 #0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 #1 0x00007efe64e259ff in __pthread_kill_internal (threadid=<optimized out>, signo=6) at ./nptl/pthread_kill.c:89 #2 0x00007efe64dd0cc2 in __GI_raise (sig=sig@entry=6) at ../sysdeps/posix/raise.c:26 #3 0x00007efe64db94ac in __GI_abort () at ./stdlib/abort.c:73 #4 0x00007efe64fbd7b6 in osmo_panic_default (fmt=0x7efe64fdb630 "failure during io_uring_queue_init(): %s\n", args=0x7ffd4bcaf000) at ./src/core/panic.c:45 #5 osmo_panic (fmt=fmt@entry=0x7efe64fdb630 "failure during io_uring_queue_init(): %s\n") at ./src/core/panic.c:80 #6 0x00007efe64fd4666 in osmo_iofd_uring_init () at ./src/core/osmo_io_uring.c:138 #7 0x00007efe65126fae in call_init (l=<optimized out>, argc=5, argv=0x7ffd4bcaf188, env=0x7ffd4bcaf1b8) at ./elf/dl-init.c:74 #8 call_init (l=<optimized out>, argc=5, argv=0x7ffd4bcaf188, env=0x7ffd4bcaf1b8) at ./elf/dl-init.c:26 #9 0x00007efe6512707c in _dl_init (main_map=0x7efe65159310, argc=5, argv=0x7ffd4bcaf188, env=0x7ffd4bcaf1b8) at ./elf/dl-init.c:121 #10 0x00007efe6513e470 in _dl_start_user () from /lib64/ld-linux-x86-64.so.2 #11 0x0000000000000005 in ?? () #12 0x00007ffd4bcb0c35 in ?? () #13 0x00007ffd4bcb0c47 in ?? () #14 0x00007ffd4bcb0c7c in ?? () #15 0x00007ffd4bcb0c86 in ?? () #16 0x00007ffd4bcb0c8b in ?? () #17 0x0000000000000000 in ?? ()

[testenv][generic] Stopping podman container [testenv][generic] + ['podman', 'kill', 'testenv-bsc-generic-osmocom-nightly-20251223-1438-1450a117-0'] testenv-bsc-generic-osmocom-nightly-20251223-1438-1450a117-0 https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifact/logs/bts-1/core: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'osmo-bts-omldummy -fCCN,EGPRS,GPRS,IPv6_NSVC,PAGING_COORDINATION,OSMUX 127.0.0.', real uid: 0, effective uid: 0, real gid: 0, effective gid: 0, execfn: '/usr/bin/osmo-bts-omldummy', platform: 'x86_64' [testenv][generic] Stopping testsuite (3027447) Error: container has already been removed [testenv] feed_watchdog_loop: podman container has stopped [testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifact...  + RC=1 + [ 1 = 0 ] + grep --color=always -o load.* + uptime load average: 1.68, 4.28, 3.79 + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.