See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/26/display/redirect?page=changes> Changes: [Pau Espin Pedrol] hnbgw: Introduce PfcpParameters in TestHdlrParameters [Oliver Smith] bsc: testenv: set MTU=1500 for lo [Pau Espin Pedrol] hnbgw: Merge TC_ps_rab_assignment_* testcases [Pau Espin Pedrol] hnbgw: Move normal condition Iu Release procedure to helper function [Pau Espin Pedrol] hnbgw: Tear down SCCP conn from CN under normal conditions ------------------------------------------ [...truncated 4.71 MiB...] 20241104145358827 DLM3UA DEBUG 0: asp-asp-dyn-2: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145358827 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer (m3ua.c:564) 20241104145358827 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:599) 20241104145358827 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145358827 DLSS7 DEBUG Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 via AS virt-msc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145358827 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=185=0.23.1 (osmo_ss7_hmrt.c:241) 20241104145358827 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) VirtMSC-SCCP(645)@6370a5bc206b: First idle individual index:0 VirtMSC-SCCP(645)@6370a5bc206b: Warning: Stopping inactive timer T_ias[0]. VirtMSC-RAN(646)@6370a5bc206b: ExpectedCreateCallback completeLayer3Information VirtMSC-RAN(646)@6370a5bc206b: Found Expect[0] for '05240103505902080910109649419066'O handled at TC_assignment_codec_fr_by_mode_modify(655) VirtMSC-RAN(646)@6370a5bc206b: Added conn table entry 0TC_assignment_codec_fr_by_mode_modify(655)3262327 VirtMSC-SCCP(645)@6370a5bc206b: Session index based on connection ID:0 VirtMSC-SCCP(645)@6370a5bc206b: Warning: Re-starting timer T_ias[0], which is already active (running or expired). 20241104145358830 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145358830 DLM3UA DEBUG 0: asp-virt-msc0-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145358830 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer (m3ua.c:564) 20241104145358830 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 (m3ua.c:599) 20241104145358830 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145358830 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS as-rkm-4 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145358830 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241104145358830 DLSS7 DEBUG XUA_AS(as-rkm-4){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) IPA-BTS0-TRX0-RSL-RSL(654)@6370a5bc206b: Enabled queueing of DChan messages VirtMGW-MGCP-0(652)@6370a5bc206b: Created Expect[0] for { connid := omit, endpoint := omit, transid := omit } to be handled at TC_assignment_codec_fr_by_mode_modify(655) VirtMSC-SCCP(645)@6370a5bc206b: Session index based on connection ID:0 VirtMSC-SCCP(645)@6370a5bc206b: vl_len:19 VirtMSC-SCCP(645)@6370a5bc206b: vl_from0 VirtMSC-SCCP(645)@6370a5bc206b: data sent by MTP3_SCCP_PORT: '0011010B030108017C060102030409267D0180'O 20241104145359034 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145359034 DLM3UA DEBUG 0: asp-virt-msc0-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145359034 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer (m3ua.c:564) 20241104145359034 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 (m3ua.c:599) 20241104145359034 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145359034 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS as-rkm-4 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145359034 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241104145359034 DLSS7 DEBUG XUA_AS(as-rkm-4){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: Rx L3 from net: { discriminator := '0110'B, tiOrSkip := { skipIndicator := '0000'B }, msgs := { rrm := { channelModeModify := { messageType := '00010000'B, channelDescription := { timeslotNumber := '001'B, channelTypeandTDMAOffset := '00001'B, octet3 := '43'O ("C"), octet4 := '67'O ("g") }, channelMode := { mode := '01'O }, vGCS_TargetModeIndication := omit, multiRateConfiguration := omit, vGCS_Ciphering_Parameters := omit, extendedTSCSet := omit } } } } VirtMGW-MGCP-0(652)@6370a5bc206b: Found Expect[0] for { line := { verb := "CRCX", trans_id := "86", ep := "rtpbridge/*@mgw", ver := "1.0" }, params := { { code := "C", val := "3b" }, { code := "L", val := "p:20, a:GSM, nt:IN" }, { code := "M", val := "recvonly" } }, sdp := omit } handled at TC_assignment_codec_fr_by_mode_modify(655) 20241104145359105 DLSS7 DEBUG 0: asp-asp-dyn-2: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145359105 DLM3UA DEBUG 0: asp-asp-dyn-2: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145359105 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer (m3ua.c:564) 20241104145359105 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:599) 20241104145359105 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145359105 DLSS7 DEBUG Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 via AS virt-msc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145359105 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=185=0.23.1 (osmo_ss7_hmrt.c:241) 20241104145359105 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) VirtMSC-SCCP(645)@6370a5bc206b: Session index based on local reference:0 VirtMSC-SCCP(645)@6370a5bc206b: DT1 will be put to the reassembly buffer VirtMSC-SCCP(645)@6370a5bc206b: DT1 data has been put to the reassembly buffer VirtMSC-SCCP(645)@6370a5bc206b: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: setverdict(pass): none -> pass TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: g_media { mgcp_ep := "rtpbridge/1@mgw", mgcp_conn := { { crcx_seen := 1, mdcx_seen := 1, crcx_seen_exp := 1, mdcx_seen_exp := 1, conn_id := '13356AEA'H, mime_type := "GSM", sample_rate := 8000, ptime := 20, rtp_pt := 3, mgw := { host := "127.0.0.3", port_nr := 10002 }, peer := { host := "127.0.0.2", port_nr := 9002 }, local_osmux_cid := 0, remote_osmux_cid := omit }, { crcx_seen := 1, mdcx_seen := 0, crcx_seen_exp := 1, mdcx_seen_exp := 0, conn_id := '154C2B8C'H, mime_type := "GSM", sample_rate := 8000, ptime := 20, rtp_pt := 3, mgw := { host := "127.0.0.3", port_nr := 11002 }, peer := { host := "1.2.3.4", port_nr := 2342 }, local_osmux_cid := 1, remote_osmux_cid := omit } }, bts := { ipa_crcx_seen := true, ipa_mdcx_seen := true, conn_id := 1, rtp_pt := 3, bts := { host := "127.0.0.2", port_nr := 9002 }, peer := { host := "127.0.0.5", port_nr := 9002 }, local_osmux_cid := omit, remote_osmux_cid := omit }, bts1 := { ipa_crcx_seen := false, ipa_mdcx_seen := false, conn_id := 1, rtp_pt := 0, bts := { host := "127.0.0.2", port_nr := 9002 }, peer := <unbound>, local_osmux_cid := 1, remote_osmux_cid := omit } } TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "TC_assignment_codec_fr_by_mode_modify": Check MGCP test expectations for g_media.mgcp_conn[0]: crcx_seen=1, crcx_seen_exp=1, mdcx_seen=1, mdcx_seen_exp=1 TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "TC_assignment_codec_fr_by_mode_modify": Check MGCP test expectations for g_media.mgcp_conn[1]: crcx_seen=1, crcx_seen_exp=1, mdcx_seen=0, mdcx_seen_exp=0 20241104145359108 DLGLOBAL NOTICE TTCN3 f_logp(): MSC instructs BSC to clear channel (logging_vty.c:1172) TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "MSC instructs BSC to clear channel" VirtMSC-SCCP(645)@6370a5bc206b: Session index based on connection ID:0 VirtMSC-SCCP(645)@6370a5bc206b: vl_len:6 VirtMSC-SCCP(645)@6370a5bc206b: vl_from0 VirtMSC-SCCP(645)@6370a5bc206b: data sent by MTP3_SCCP_PORT: '000420040100'O 20241104145359109 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145359109 DLM3UA DEBUG 0: asp-virt-msc0-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145359109 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer (m3ua.c:564) 20241104145359109 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 (m3ua.c:599) 20241104145359109 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145359109 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS as-rkm-4 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145359109 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241104145359109 DLSS7 DEBUG XUA_AS(as-rkm-4){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241104145359109 DLSS7 DEBUG 0: asp-asp-dyn-2: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145359109 DLM3UA DEBUG 0: asp-asp-dyn-2: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145359109 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer (m3ua.c:564) 20241104145359109 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:599) 20241104145359109 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145359109 DLSS7 DEBUG Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 via AS virt-msc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145359109 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=185=0.23.1 (osmo_ss7_hmrt.c:241) 20241104145359109 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) VirtMSC-SCCP(645)@6370a5bc206b: Session index based on local reference:0 VirtMSC-SCCP(645)@6370a5bc206b: DT1 will be put to the reassembly buffer VirtMSC-SCCP(645)@6370a5bc206b: DT1 data has been put to the reassembly buffer VirtMSC-SCCP(645)@6370a5bc206b: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "Got BSSMAP Clear Complete" 20241104145359110 DLGLOBAL NOTICE TTCN3 f_logp(): Got BSSMAP Clear Complete (logging_vty.c:1172) VirtMSC-RAN(646)@6370a5bc206b: Deleted conn table entry 0TC_assignment_codec_fr_by_mode_modify(655)3262327 VirtMSC-SCCP(645)@6370a5bc206b: Session index based on connection ID:0 20241104145359112 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145359112 DLM3UA DEBUG 0: asp-virt-msc0-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145359112 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer (m3ua.c:564) 20241104145359112 DLM3UA DEBUG 0: asp-virt-msc0-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 (m3ua.c:599) 20241104145359112 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145359112 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS as-rkm-4 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145359112 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241104145359112 DLSS7 DEBUG XUA_AS(as-rkm-4){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241104145359112 DLSS7 DEBUG 0: asp-asp-dyn-2: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145359112 DLM3UA DEBUG 0: asp-asp-dyn-2: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145359112 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer (m3ua.c:564) 20241104145359112 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:599) 20241104145359112 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145359112 DLSS7 DEBUG Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 via AS virt-msc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145359112 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=185=0.23.1 (osmo_ss7_hmrt.c:241) 20241104145359112 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) VirtMSC-SCCP(645)@6370a5bc206b: Session index based on local reference:0 VirtMSC-SCCP(645)@6370a5bc206b: RLC received in state:disconnect_pending TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "Got RSL RR Release" 20241104145359112 DLGLOBAL NOTICE TTCN3 f_logp(): Got RSL RR Release (logging_vty.c:1172) TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "Got RSL Deact SACCH" 20241104145359113 DLGLOBAL NOTICE TTCN3 f_logp(): Got RSL Deact SACCH (logging_vty.c:1172) 20241104145400400 DLSS7 DEBUG 0: asp-asp-dyn-2: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145400400 DLM3UA DEBUG 0: asp-asp-dyn-2: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145400400 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer (m3ua.c:564) 20241104145400400 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=2=0.0.2 (m3ua.c:599) 20241104145400400 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145400400 DLSS7 DEBUG Found route for dpc=2=0.0.2: pc=2=0.0.2 mask=0x3fff=7.255.7 via AS virt-msc1 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145400400 DLSS7 ERROR Unable to route HMRT message: the AS virt-msc1 is down (osmo_ss7_hmrt.c:232) 20241104145400400 DLSS7 DEBUG 0: asp-asp-dyn-2: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145400400 DLM3UA DEBUG 0: asp-asp-dyn-2: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241104145400400 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer (m3ua.c:564) 20241104145400400 DLM3UA DEBUG 0: asp-asp-dyn-2: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=3=0.0.3 (m3ua.c:599) 20241104145400400 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=3=0.0.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241104145400400 DLSS7 DEBUG Found route for dpc=3=0.0.3: pc=3=0.0.3 mask=0x3fff=7.255.7 via AS virt-msc2 proto=m3ua (osmo_ss7_hmrt.c:227) 20241104145400400 DLSS7 ERROR Unable to route HMRT message: the AS virt-msc2 is down (osmo_ss7_hmrt.c:232) TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: "Got RSL RF Chan Rel, sending Rel Ack" 20241104145401116 DLGLOBAL NOTICE TTCN3 f_logp(): Got RSL RF Chan Rel, sending Rel Ack (logging_vty.c:1172) TC_assignment_codec_fr_by_mode_modify(655)@6370a5bc206b: Final verdict of PTC: pass MTC@6370a5bc206b: talloc reports "struct bsc_subscr" x 0, expecting 0 MTC@6370a5bc206b: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 20241104145401238 DLINP NOTICE virt-msc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:265) 20241104145401239 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831) 20241104145401239 DLSS7 INFO 0: asp-virt-msc0-0: xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (osmo_ss7_asp.c:769) 20241104145401239 DLSS7 NOTICE 0: asp-virt-msc0-0: xUA SRV SHUTDOWN_EVENT (osmo_ss7_asp.c:792) 20241104145401239 DLINP NOTICE virt-msc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:265) 20241104145401239 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831) 20241104145401239 DLSS7 INFO 0: asp-virt-msc0-0: xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (osmo_ss7_asp.c:769) 20241104145401239 DLSS7 NOTICE 0: asp-virt-msc0-0: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (osmo_ss7_asp.c:777) 20241104145401239 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:673) 20241104145401239 DLSS7 DEBUG 0: asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145401239 DLSS7 INFO virt-msc0-0: connection closed (osmo_ss7_asp.c:1079) 20241104145401239 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (osmo_ss7_asp.c:1085) 20241104145401239 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:683) 20241104145401239 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:364) 20241104145401239 DLSS7 DEBUG XUA_AS(virt-msc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:467) VirtMSC-SCCP(645)@6370a5bc206b: Final verdict of PTC: none 20241104145401239 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:115) 20241104145401239 DLSS7 DEBUG XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:115) VirtMSC-M3UA(647)@6370a5bc206b: Final verdict of PTC: none 20241104145401239 DLM3UA NOTICE 0: asp-asp-clnt-msc-0: Rx DUNA() for 0.23.1/0, (xua_snm.c:368) 20241104145401239 DMSC NOTICE (msc0) now unreachable: N-PCSTATE ind: pc=185 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (osmo_bsc_sigtran.c:277) 20241104145401239 DRESET NOTICE bssmap_reset(msc-0)[0x5630328931b0]{DISCONNECTED}: link lost (bssmap_reset.c:91) 20241104145401239 DMSC NOTICE (msc0) BSSMAP association is down (a_reset.c:54) 20241104145401239 DLCS ERROR Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) 20241104145401239 DLCTRL ERROR Failed to parse ip access message: -104 (Connection reset by peer) (control_if.c:372) VirtMSC-RAN(646)@6370a5bc206b: Final verdict of PTC: none VirtMSC-STATS(644)@6370a5bc206b: Final verdict of PTC: none IPA-CTRL-CLI-IPA(651)@6370a5bc206b: Final verdict of PTC: none MTC@6370a5bc206b: setverdict(pass): none -> pass 20241104145401241 DLINP NOTICE E1TS(0:2) Sign link vanished, dead socket (ipaccess.c:386) 20241104145401241 DLINP NOTICE E1TS(0:2) Forcing socket shutdown (ipaccess.c:96) 20241104145401241 DLINP NOTICE E1L(0) Signalling link down (bts_ipaccess_nanobts.c:770) 20241104145401241 DLINP NOTICE (bts=0) Dropping OML link: link down (bts_ipaccess_nanobts.c:539) 20241104145401241 DLMI ERROR (bts=0,trx=0) Lost E1 OML link (osmo_bsc_main.c:487) 20241104145401241 DLMI ERROR (bts=0,trx=0) Lost E1 OSMO link (osmo_bsc_main.c:487) 20241104145401241 DLINP NOTICE (bts=0,trx=0) Dropping RSL link: OML link drop (bts_ipaccess_nanobts.c:514) 20241104145401241 DLMI ERROR (bts=0,trx=0) Lost E1 RSL link (osmo_bsc_main.c:487) <0013> input/ipa.c:68 127.0.0.1:3002 connection closed with server <000d> abis.c:417 E1L(0) Signalling link down <000d> abis.c:98 OML link was closed early within 9 seconds. If this situation persists, please check your BTS and BSC configuration files for errors. A common error is a mismatch between unit_id configuration parameters of BTS and BSC. <0001> bts_shutdown_fsm.c:268 BTS_SHUTDOWN(bts0)[0x5618d9a58be0]{NONE}: Shutting down BTS, exit 1, reason: Abis close IPA-BTS0-TRX0-RSL-RSL(654)@6370a5bc206b: Final verdict of PTC: none VirtSMLC-SCCP(648)@6370a5bc206b: Final verdict of PTC: none VirtSMLC-BSSAP_LE(649)@6370a5bc206b: Final verdict of PTC: none 20241104145401241 DLINP NOTICE virt-smlc-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:265) 20241104145401241 DLSS7 DEBUG 0: asp-virt-smlc-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831) 20241104145401241 DLSS7 INFO 0: asp-virt-smlc-0: xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (osmo_ss7_asp.c:769) 20241104145401241 DLSS7 NOTICE 0: asp-virt-smlc-0: xUA SRV SHUTDOWN_EVENT (osmo_ss7_asp.c:792) 20241104145401241 DLINP NOTICE virt-smlc-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:265) 20241104145401241 DLSS7 DEBUG 0: asp-virt-smlc-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831) 20241104145401241 DLSS7 INFO 0: asp-virt-smlc-0: xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (osmo_ss7_asp.c:769) 20241104145401241 DLSS7 NOTICE 0: asp-virt-smlc-0: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (osmo_ss7_asp.c:777) 20241104145401241 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:673) 20241104145401241 DLSS7 DEBUG 0: asp-virt-smlc-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241104145401241 DLSS7 INFO virt-smlc-0: connection closed (osmo_ss7_asp.c:1079) 20241104145401241 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (osmo_ss7_asp.c:1085) 20241104145401241 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:683) 20241104145401241 DLSS7 DEBUG XUA_AS(virt-smlc){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:364) 20241104145401241 DLSS7 DEBUG XUA_AS(virt-smlc){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:467) 20241104145401241 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:115) 20241104145401241 DLSS7 DEBUG XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:115) 20241104145401241 DLM3UA NOTICE 0: asp-asp-clnt-msc-0: Rx DUNA() for 0.23.6/0, (xua_snm.c:368) 20241104145401241 DLCS ERROR Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) IPA-BTS0-TRX0-RSL-IPA(653)@6370a5bc206b: Final verdict of PTC: none VirtSMLC-M3UA(650)@6370a5bc206b: Final verdict of PTC: none VirtMGW-MGCP-0(652)@6370a5bc206b: Final verdict of PTC: none MTC@6370a5bc206b: Setting final verdict of the test case. MTC@6370a5bc206b: Local verdict of MTC: pass MTC@6370a5bc206b: Local verdict of PTC VirtMSC-STATS(644): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtMSC-SCCP(645): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtMSC-RAN(646): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtMSC-M3UA(647): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtSMLC-SCCP(648): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtSMLC-BSSAP_LE(649): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtSMLC-M3UA(650): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC IPA-CTRL-CLI-IPA(651): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC VirtMGW-MGCP-0(652): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(653): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC IPA-BTS0-TRX0-RSL-RSL(654): none (pass -> pass) MTC@6370a5bc206b: Local verdict of PTC TC_assignment_codec_fr_by_mode_modify(655): pass (pass -> pass) MTC@6370a5bc206b: Test case TC_assignment_codec_fr_by_mode_modify finished. Verdict: pass MTC@6370a5bc206b: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> BSC_Tests.TC_assignment_codec_fr_by_mode_modify pass'. Mon Nov 4 14:54:01 UTC 2024 ====== BSC_Tests.TC_assignment_codec_fr_by_mode_modify pass ====== Saving talloc report from 127.0.0.1:4242 to BSC_Tests.TC_assignment_codec_fr_by_mode_modify.talloc ERROR: /tmp/watchdog was not created, exiting Error: OCI runtime error: crun: fail startup setns `mnt`: Bad file descriptor [testenv] podman container crashed! [testenv][generic] Testsuite is done [testenv][generic] Stopping testsuite (2521299) [testenv][generic] Merging log files [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', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache/git',> '-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/_cache/podman/osmo-ttcn3-hacks/bsc>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/26/artifact/logs/testsuite',> 'testenv-bsc-generic-osmocom-nightly-20241104-1438-1e7b9a3c-0', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh> BSC_Tests --rm >/dev/null'] Error: can only create exec sessions on running containers: container state improper [testenv][generic] Command failed unexpectedly [testenv][generic] Command: ['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', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache/git',> '-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/_cache/podman/osmo-ttcn3-hacks/bsc>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/26/artifact/logs/testsuite',> 'testenv-bsc-generic-osmocom-nightly-20241104-1438-1e7b9a3c-0', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh> BSC_Tests --rm >/dev/null'] [testenv][generic] Returncode: 255 Traceback (most recent call last): File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/./testenv.py",> line 110, in <module> main() File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/./testenv.py",> line 99, in main run() File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/./testenv.py",> line 58, in run testenv.testsuite.run(cfg) File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/testenv/testsuite.py",> line 204, in run merge_log_files(cfg) File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/testenv/testsuite.py",> line 130, in merge_log_files testenv.cmd.run(cmd, cwd=cwd) File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/testenv/cmd.py",> line 101, in run return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/testenv/podman.py",> line 149, in exec_cmd return testenv.cmd.run( ^^^^^^^^^^^^^^^^ File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/testenv/cmd.py",> line 118, in run exit_error_cmd(p, "Command failed unexpectedly") File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv/testenv/cmd.py",> line 66, in exit_error_cmd raise RuntimeError("shell command related error, find details right above this python trace") RuntimeError: shell command related error, find details right above this python trace [testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/26/artifact…  Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.