See <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2781/display/redirect?page=changes> Changes: [Pau Espin Pedrol] sgsn: Introduce test TC_stat_rnc_sctp_disconnected [Pau Espin Pedrol] smlc: Use static sigtran config between osmo-msc and osmo-stp [Pau Espin Pedrol] smlc: Add suport to obtain statsd output [Pau Espin Pedrol] sctp: Introduce test TC_stat_bsc_sctp_disconnected ------------------------------------------ [...truncated 3.30 MiB...] 20250715100124558 DLSS7 DEBUG 0: ASP(virt-rnc0-0) Tx 16 bytes: [L2]> 01 00 04 03 00 00 00 10 00 06 00 08 00 00 00 02 (ss7_asp.c:1383) 20250715100124558 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_INACTIVE}: state_chg to ASP_ACTIVE (xua_asp_fsm.c:663) 20250715100124558 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind (xua_asp_fsm.c:377) 20250715100124558 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_INACTIVE}: state_chg to AS_ACTIVE (xua_as_fsm.c:565) 20250715100124558 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: Broadcasting NOTIFY Type State Change:AS Active () to all non-DOWN ASPs (xua_as_fsm.c:116) 20250715100124558 DLSS7 INFO 0: ASP(virt-rnc0-0) Tx NOTIFY Type State Change:AS Active () (xua_as_fsm.c:142) 20250715100124558 DLSS7 DEBUG 0: ASP(virt-rnc0-0) Tx 24 bytes: [L2]> 01 00 00 01 00 00 00 18 00 0d 00 08 00 01 00 03 00 06 00 08 00 00 00 02 (ss7_asp.c:1383) 20250715100124558 DLSS7 INFO 0: RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=virt-rnc0,st=available) Creating route: pc=195=0.24.3 mask=0x3fff=7.255.7 prio=5 dyn via AS virt-rnc0 proto=m3ua (ss7_route.c:143) 20250715100124558 DLSS7 NOTICE 0: RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=virt-rnc0,st=available) changed to status 'available' (ss7_route.c:409) 20250715100124558 DLSS7 DEBUG 0: ASP(sgsn0-0) Tx 24 bytes: [L2]> 01 00 02 02 00 00 00 18 00 06 00 08 00 00 00 03 00 12 00 08 00 00 00 c3 (ss7_asp.c:1383) 20250715100124558 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: No Layer Manager, dropping M-ASP_ACTIVE.indication (xua_asp_fsm.c:122) 20250715100124558 DLM3UA NOTICE 0: ASP(asp-clnt-OsmoSGSN-A) Rx DAVA() for 0.24.3/0 (xua_snm.c:478) 20250715100124558 DLSS7 NOTICE 0: RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=as-clnt-OsmoSGSN-A,st=unavailable) changed to status 'available' (ss7_route.c:409) 20250715100124680 DLGSUP NOTICE GSUP connecting to 127.0.0.103:4222 (gsup_client.c:77) 20250715100124680 DLINP NOTICE 127.0.0.103:4222 connection done (ipa.c:144) 20250715100124680 DLGSUP INFO GSUP link to 127.0.0.103:4222 UP (gsup_client.c:154) 20250715100124680 DLGSUP DEBUG GSUP sending PING (gsup_client.c:291) SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: Established a new IPA connection (conn_id=2) 20250715100124683 DLINP NOTICE received ID_GET for unit ID 0/0/0 (ipa.c:617) SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: 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) } } } } 20250715100124684 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:228) SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 6, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E00'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: IPA ID RESP: { { len := 6, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E00'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } 20250715100125353 DLSS7 DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076) 20250715100125353 DLM3UA DEBUG 0: ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797) 20250715100125353 DLM3UA DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599) 20250715100125353 DLM3UA DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer(): M3UA data header: opc=195=0.24.3 dpc=188=0.23.4 sls=0 (m3ua.c:642) 20250715100125353 DLSS7 DEBUG 0: m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (ss7_hmrt.c:248) 20250715100125353 DLSS7 INFO 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT loookup: OPC=195=0.24.3,DPC=188=0.23.4,SLS=0 -> eSLS=0: picked Normal Route via 'sgsn0' round-robin style (available) (ss7_combined_linkset.c:297) 20250715100125353 DLSS7 DEBUG 0: Found route for dpc=188=0.23.4: pc=188=0.23.4 mask=0x3fff=7.255.7 prio=5 via AS sgsn0 proto=m3ua (ss7_hmrt.c:193) 20250715100125353 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203) 20250715100125353 DLSS7 DEBUG XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562) 20250715100125353 DLSS7 DEBUG 0: ASP(sgsn0-0) Tx 68 bytes: [L2]> 01 00 01 01 00 00 00 44 02 10 00 31 00 00 00 c3 00 00 00 bc 03 02 00 00 09 00 03 07 0b 04 43 bc 00 8e 04 43 c3 00 8e 11 00 09 00 0d 00 00 02 00 04 40 01 40 00 03 00 01 80 00 00 00 00 06 00 08 00 00 00 03 (ss7_asp.c:1383) 20250715100125354 DMM DEBUG Rx CL IM (Reset) (ranap_common_cn.c:436) 20250715100125354 DMM DEBUG Decoding message RANAP_ResetIEs (ranap_decoder.c:2011) (ranap_decoder.c:2011) 20250715100125354 DLSS7 DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076) 20250715100125354 DLM3UA DEBUG 0: ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797) 20250715100125354 DLM3UA DEBUG 0: ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599) 20250715100125354 DLM3UA DEBUG 0: ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=11 (m3ua.c:642) 20250715100125354 DLSS7 DEBUG 0: m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing (ss7_hmrt.c:248) 20250715100125354 DLSS7 INFO 0: CombinedLinkset(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5) RT loookup: OPC=188=0.23.4,DPC=195=0.24.3,SLS=11 -> eSLS=11: picked Normal Route via 'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297) 20250715100125354 DLSS7 DEBUG 0: Found route for dpc=195=0.24.3: pc=195=0.24.3 mask=0x3fff=7.255.7 prio=5 via AS virt-rnc0 proto=m3ua (ss7_hmrt.c:193) 20250715100125354 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203) 20250715100125354 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562) 20250715100125354 DLSS7 DEBUG 0: ASP(virt-rnc0-0) Tx 60 bytes: [L2]> 01 00 01 01 00 00 00 3c 02 10 00 2c 00 00 00 bc 00 00 00 c3 03 00 00 0b 09 00 03 07 0b 04 43 c3 00 8e 04 43 bc 00 8e 0c 20 09 00 08 00 00 01 00 03 00 01 80 00 06 00 08 00 00 00 02 (ss7_asp.c:1383) SGSN_Test_0-RAN(1424)@0801b8f34c04: RANAP: Received RESET-ACK in response to RESET, we're ready to go! TC_iu_attach-BVCI196(1411)@0801b8f34c04: Adding Client=TC_iu_attach(1430), IMSI='262420000001001'H, TLLI='E8B510DF'O, index=0 SGSN_Test-GSUP(1428)@0801b8f34c04: Created GsupExpect[0] for "262420000001001" to be handled at TC_iu_attach(1430) TC_iu_attach(1430)@0801b8f34c04: Sending InitialUE: { discriminator := '0000'B, tiOrSkip := { skipIndicator := '0000'B }, msgs := { gprs_mm := { attachRequest := { messageType := '00000000'B, msNetworkCapability := { lengthIndicator := 0, msNetworkCapabilityV := { gea1bit := '1'B, smCapabilitiesviaDedicatedChannels := '1'B, smCapabilitiesviaGPRSChannels := '0'B, ucs2Support := '1'B, ssScreeningIndicator := '01'B, solSACapability := '0'B, revisionLevelIndicatior := '1'B, pFCFeatureMode := omit, extendedGEAbits := omit, lcsVAcapability := omit, pSInterRATHOtoUTRANIuModeCapability := omit, pSInterRATHOtoEUTRANS1ModeCapability := omit, eMMCombinedProceduresCapability := omit, iSRSupport := omit, sRVCCtoGERANUTRANCapability := omit, ePCCapability := omit, nFCapability := omit, gERANNertworkSharingCapability := omit, spare_octets := omit } }, attachType := { attachType := '001'B, for_l3 := '0'B }, gprsCKSN := { keySequence := '111'B, spare := '0'B }, drxParam := { splitPGCycleCode := '00'O, nonDRXTimer := '000'B, splitOnCCCH := '0'B, cnSpecificDRXCycleLength := '0000'B }, mobileIdentity := { lengthIndicator := 0, mobileIdentityV := { typeOfIdentity := '001'B, oddEvenInd_identity := { imsi := { oddevenIndicator := '1'B, digits := '262420000001001'H, fillerDigit := omit } } } }, oldRoutingAreaID := { mccDigit1 := '2'H, mccDigit2 := '6'H, mccDigit3 := '2'H, mncDigit3 := 'F'H, mncDigit1 := '4'H, mncDigit2 := '2'H, lac := '76E0'O, rac := '26'O ("&") }, msRACap := { lengthIndicator := 0, msRadioAccessCapabilityV := { { mSRACapabilityValues := { mSRACapabilityValuesExclude1111 := { accessTechnType := '0001'B, accessCapabilities := { lengthIndicator := 0, accessCapabilities := { rfPowerCapability := '001'B, presenceBitA5 := '0'B, a5bits := { a51 := '1'B, a52 := '0'B, a53 := '1'B, a54 := '0'B, a55 := '0'B, a56 := '0'B, a57 := '0'B }, esind := '1'B, psbit := '0'B, vgcs := '0'B, vbs := '0'B, presenceBitMultislot := '1'B, multislotcap := { presenceBitHscsd := '0'B, hscsdmultislotclass := omit, presenceBitGprs := '0'B, gprsmultislot := omit, presenceBitSms := '0'B, multislotCap_SMS := omit, multislotCapAdditionsAfterRel97 := { presenceBitEcsdmulti := '0'B, ecsdmultislotclass := omit, presenceBitEgprsmulti := '0'B, multislotCap_EGPRS := omit, presenceBitDtmGprsmulti := '0'B, multislotCapdtmgprsmultislotsubclass := omit } }, accessCapAdditionsAfterRel97 := omit }, spare_bits := omit } } }, presenceBitMSRACap := '0'B } } }, ptmsiSignature := omit, reqGPRStimer := omit, tmsiStatus := omit, pC_LCSCapability := omit, mobileStationClassmark2 := omit, mobileStationClassmark3 := omit, supportedCodecs := omit, uENetworkCapability := omit, additionalMobileIdentity := omit, routingAreaIdentification2 := omit, voiceDomainandUEsUsageSetting := omit, deviceProperties := omit, p_TMSI_Type := omit, mS_NetworkFeatureSupport := omit, oldLocationAreaIdentification := omit, additionalUpdateType := omit, tMSIBasedNRIcontainer := omit, t3324 := omit, t3312_ExtendedValue := omit, extendedDRXParameters := omit } } } } SGSN_Test_0-RAN(1424)@0801b8f34c04: Added conn table entry 0TC_iu_attach(1430)4252920 SGSN_Test_0-SCCP(1423)@0801b8f34c04: First idle individual index:0 SGSN_Test_0-RAN(1424)@0801b8f34c04: patch: N(SD) for ConnIdx 0 set to 1 20250715100125731 DLSS7 DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076) 20250715100125731 DLM3UA DEBUG 0: ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797) 20250715100125731 DLM3UA DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599) 20250715100125731 DLM3UA DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer(): M3UA data header: opc=195=0.24.3 dpc=188=0.23.4 sls=0 (m3ua.c:642) 20250715100125731 DLSS7 DEBUG 0: m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (ss7_hmrt.c:248) 20250715100125731 DLSS7 DEBUG 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=195=0.24.3,DPC=188=0.23.4,SLS=0 -> eSLS=0: use Normal Route via 'sgsn0' (ss7_combined_linkset.c:275) 20250715100125731 DLSS7 DEBUG 0: Found route for dpc=188=0.23.4: pc=188=0.23.4 mask=0x3fff=7.255.7 prio=5 via AS sgsn0 proto=m3ua (ss7_hmrt.c:193) 20250715100125731 DLSS7 DEBUG 0: rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203) 20250715100125731 DLSS7 DEBUG XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562) 20250715100125731 DLSS7 DEBUG 0: ASP(sgsn0-0) Tx 144 bytes: [L2]> 01 00 01 01 00 00 00 90 02 10 00 7d 00 00 00 c3 00 00 00 bc 03 02 00 00 01 40 e4 f8 02 02 06 04 43 bc 00 8e 04 04 43 c3 00 8e 0f 58 00 13 40 54 00 00 07 00 03 40 01 80 00 0f 40 06 00 62 f2 24 12 34 00 37 40 01 00 00 3a 40 08 00 62 f2 24 12 34 00 00 00 10 40 1d 1c 08 01 01 d5 71 00 00 08 29 26 24 00 00 00 01 10 62 f2 24 76 e0 26 05 12 c7 42 20 00 00 4f 40 03 00 00 17 00 56 40 05 62 f2 24 09 26 00 00 00 00 00 06 00 08 00 00 00 03 (ss7_asp.c:1383) 20250715100125731 DMM DEBUG Rx CO IM (Initial UE Message) (ranap_common_cn.c:41) 20250715100125731 DMM DEBUG Decoding message RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635) 20250715100125731 DRANAP NOTICE handle_co_initial(dir=1, proc=19) (iu_client.c:660) 20250715100125731 DRANAP NOTICE New RNC 262-42-2342 at RI=2,PC=195,SSN=142 (iu_client.c:244) 20250715100125731 DRANAP NOTICE RNC 262-42-2342: new LAC/RAC 262-42-4660-0 (iu_client.c:356) 20250715100126104 DLINP NOTICE sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348) 20250715100126104 DLSS7 DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076) 20250715100126104 DLSS7 INFO 0: ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000) 20250715100126104 DLSS7 NOTICE 0: ASP(sgsn0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023) 20250715100126104 DLINP NOTICE sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348) 20250715100126104 DLSS7 DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076) 20250715100126104 DLSS7 INFO 0: ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000) 20250715100126104 DLSS7 NOTICE 0: ASP(sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008) 20250715100126104 DLINP NOTICE SRVCONN(sgsn0-0,r=127.0.0.10:1905<->l=127.0.0.200:2905) connection closed with client (stream_srv.c:821) 20250715100126104 DLSS7 DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076) 20250715100126104 DLSS7 INFO sgsn0-0: connection closed (ss7_asp.c:1334) 20250715100126104 DLSS7 DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340) 20250715100126104 DLSS7 DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:787) 20250715100126104 DLSS7 DEBUG XUA_ASP(sgsn0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:401) 20250715100126104 DLSS7 DEBUG XUA_AS(sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377) 20250715100126104 DLSS7 DEBUG XUA_AS(sgsn0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:598) 20250715100126104 DLSS7 DEBUG XUA_AS(sgsn0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116) 20250715100126104 DLSS7 NOTICE 0: RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=sgsn0,st=available) changed to status 'unavailable' (ss7_route.c:409) 20250715100126104 DLSS7 NOTICE 0: RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=sgsn0,st=available) changed to status 'unavailable' (ss7_route.c:409) 20250715100126104 DLSS7 DEBUG 0: ASP(virt-rnc0-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 02 00 12 00 08 00 00 00 bc (ss7_asp.c:1383) 20250715100126104 DLSS7 DEBUG XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:122) 20250715100126104 DLSS7 DEBUG XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:122) Segmentation fault (core dumped) SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: IPA: Closed SGSN_Test-GSUP(1428)@0801b8f34c04: setverdict(fail): none -> fail reason: "GSUP Connection Lost", new component reason: "GSUP Connection Lost" MC@0801b8f34c04: Test Component 1428 has requested to stop MTC. Terminating current testcase execution. SGSN_Test-GSUP(1428)@0801b8f34c04: Final verdict of PTC: fail reason: "GSUP Connection Lost" SGSN_Test-GSUP-IPA(1427)@0801b8f34c04: Final verdict of PTC: none -NSVCI97(1412)@0801b8f34c04: Final verdict of PTC: none 20250715100126106 DLINP NOTICE virt-rnc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348) 20250715100126106 DLSS7 DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076) 20250715100126106 DLSS7 INFO 0: ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000) 20250715100126106 DLSS7 NOTICE 0: ASP(virt-rnc0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023) 20250715100126106 DLINP NOTICE virt-rnc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348) 20250715100126106 DLSS7 DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076) 20250715100126106 DLSS7 INFO 0: ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000) 20250715100126106 DLSS7 NOTICE 0: ASP(virt-rnc0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008) 20250715100126106 DLINP NOTICE SRVCONN(virt-rnc0-0,r=127.0.0.104:23908<->l=127.0.0.200:2905) connection closed with client (stream_srv.c:821) SGSN_Test_0-SCCP(1423)@0801b8f34c04: Final verdict of PTC: none 20250715100126106 DLSS7 DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076) 20250715100126106 DLSS7 INFO virt-rnc0-0: connection closed (ss7_asp.c:1334) 20250715100126106 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340) 20250715100126106 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:787) SGSN_Test-Gb0-BSSGP0(1409)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-Gb1-NS1(1413)@0801b8f34c04: Final verdict of PTC: none SGSN_Test_0-RAN(1424)@0801b8f34c04: Final verdict of PTC: none 20250715100126106 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:401) 20250715100126106 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377) 20250715100126106 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:598) 20250715100126106 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116) 20250715100126106 DLSS7 NOTICE 0: RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=virt-rnc0,st=available) changed to status 'unavailable' (ss7_route.c:409) 20250715100126106 DLSS7 NOTICE 0: RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=virt-rnc0,st=available) changed to status 'unavailable' (ss7_route.c:409) 20250715100126106 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:122) 20250715100126106 DLSS7 DEBUG XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:122) TC_iu_attach-BVCI210(1416)@0801b8f34c04: Final verdict of PTC: none -NSVCI98(1417)@0801b8f34c04: Final verdict of PTC: none TC_iu_attach-BVCI220(1421)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-Gb0-NS0(1408)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-Gb2-BSSGP2(1419)@0801b8f34c04: Final verdict of PTC: none TC_iu_attach-BVCI196(1411)@0801b8f34c04: Final verdict of PTC: none -NSVCI99(1422)@0801b8f34c04: Final verdict of PTC: none SGSN_Test_0-M3UA(1425)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-Gb2-NS2(1418)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-RNC_GTP(1426)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-GGSN_GTP(1429)@0801b8f34c04: Final verdict of PTC: none TC_iu_attach(1430)@0801b8f34c04: Final verdict of PTC: none -NSVCI99-provIP(1420)@0801b8f34c04: Final verdict of PTC: none -NSVCI97-provIP(1410)@0801b8f34c04: Final verdict of PTC: none SGSN_Test-Gb1-BSSGP1(1414)@0801b8f34c04: Final verdict of PTC: none -NSVCI98-provIP(1415)@0801b8f34c04: Final verdict of PTC: none MTC@0801b8f34c04: Setting final verdict of the test case. MTC@0801b8f34c04: Local verdict of MTC: none MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-Gb0-NS0(1408): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(1409): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC -NSVCI97-provIP(1410): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC TC_iu_attach-BVCI196(1411): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC -NSVCI97(1412): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-Gb1-NS1(1413): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(1414): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC -NSVCI98-provIP(1415): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC TC_iu_attach-BVCI210(1416): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC -NSVCI98(1417): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-Gb2-NS2(1418): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(1419): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC -NSVCI99-provIP(1420): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC TC_iu_attach-BVCI220(1421): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC -NSVCI99(1422): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test_0-SCCP(1423): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test_0-RAN(1424): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test_0-M3UA(1425): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-RNC_GTP(1426): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-GSUP-IPA(1427): none (none -> none) MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-GSUP(1428): fail (none -> fail) reason: "GSUP Connection Lost" MTC@0801b8f34c04: Local verdict of PTC SGSN_Test-GGSN_GTP(1429): none (fail -> fail) MTC@0801b8f34c04: Local verdict of PTC TC_iu_attach(1430): none (fail -> fail) MTC@0801b8f34c04: Test case TC_iu_attach finished. Verdict: fail reason: GSUP Connection Lost MTC@0801b8f34c04: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/ttcn3-tcpdump-stop.sh> SGSN_Tests_Iu.TC_iu_attach fail'. Tue Jul 15 10:01:26 UTC 2025 ------ SGSN_Tests_Iu.TC_iu_attach fail ------ Saving talloc report from 127.0.0.10:4245 to SGSN_Tests_Iu.TC_iu_attach.talloc [testenv][generic] Looking for a coredump on lxc host [testenv][generic] + ['ip', '-j', '-o', '-4', 'addr', 'show', 'dev', 'eth0'] [testenv][generic] Coredump found and copied to log dir [testenv][generic] sgsn unexpected exit during SGSN_Tests_Iu.TC_iu_attach! rc=0 [testenv][generic] Waiting until test has stopped... 20250715100128104 DLSS7 DEBUG XUA_AS(sgsn0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349) 20250715100128104 DLSS7 DEBUG XUA_AS(sgsn0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:657) 20250715100128104 DLSS7 DEBUG XUA_AS(sgsn0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:667) 20250715100128107 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349) 20250715100128107 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:657) 20250715100128107 DLSS7 DEBUG XUA_AS(virt-rnc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:667) Traceback (most recent call last): File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 420, in main_run_commands interact.connect() File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/vty.py", line 84, in connect super().connect() File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 88, in connect self.socket.connect((self.host, int(self.port))) ConnectionRefusedError: [Errno 111] Connection refused Waiting for packet dumper to finish... 0 (prev_count=-1, count=128136) Waiting for packet dumper to finish... 1 (prev_count=128136, count=129312) MTC@0801b8f34c04: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/ttcn3-tcpdump-stop.sh> SGSN_Tests_Iu.TC_iu_attach fail' was executed successfully (exit status: 0). MTC@0801b8f34c04: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/ttcn3-tcpdump-start.sh> SGSN_Tests_Iu.TC_iu_attach_encr'. ------ SGSN_Tests_Iu.TC_iu_attach_encr ------ Tue Jul 15 10:01:31 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2781/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2781/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap.stdout> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [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-sgsn-test/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-sgsn-test/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/sgsn>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-sgsn-generic-osmocom-nightly-20250715-0948-92867e95-0', 'sh', '-c', 'gdb --batch /usr/bin/osmo-sgsn <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2781/artifact/logs/core> -ex bt | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2781/artifact/logs/core.backtrace']> [New LWP 9248] 196 ./src/gsm/gsm48.c: [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `osmo-sgsn'. Program terminated with signal SIGSEGV, Segmentation fault. #0 0x00007f173fc47ef7 in osmo_rai_name_buf (buf=0x7f173ec1e0b3 "262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at ./src/gsm/gsm48.c:196 No such file or directory. #0 0x00007f173fc47ef7 in osmo_rai_name_buf (buf=0x7f173ec1e0b3 "262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at ./src/gsm/gsm48.c:196 #1 0x00007f173fc47f85 in osmo_rai_name (rai=rai@entry=0x20) at ./src/gsm/gsm48.c:209 #2 0x000055e35173bba1 in sgsn_ranap_iu_event (ctx=0x0, type=RANAP_IU_EVENT_NEW_AREA, data=0x7ffcc479b730) at ./src/sgsn/gprs_ranap.c:147 #3 0x00007f173fa42ea4 in global_iu_event_new_area (rai=0x7ffcc479b726, rnc_id=0x7ffcc479b714) at ./src/iu_client.c:153 #4 iu_rnc_register (addr=0x7ffcc479b726, rai=0x7ffcc479b726, rnc_id=0x7ffcc479b714) at ./src/iu_client.c:360 #5 ranap_handle_co_initial_ue (ies=0x55e35d888d98, ctx=0x7ffcc479b726) at ./src/iu_client.c:500 #6 cn_ranap_handle_co_initial (ctx=ctx@entry=0x7ffcc479bc10, message=message@entry=0x7ffcc479b8a0) at ./src/iu_client.c:670 #7 0x00007f173fa409f5 in ranap_cn_rx_co (cb=cb@entry=0x7f173fa42a30 <cn_ranap_handle_co_initial>, priv=priv@entry=0x7ffcc479bc10, data=<optimized out>, len=<optimized out>) at ./src/ranap_common_cn.c:422 #8 0x00007f173fa43c5d in sccp_sap_up (oph=0x55e35d9206b8, _scu=0x55e35d90b410) at ./src/iu_client.c:1041 #9 0x00007f173fbed1b0 in _osmo_fsm_inst_dispatch (fi=0x55e35d910fc0, event=5, data=0x55e35d917410, file=0x7f173fb6c648 "sccp_scoc.c", line=820) at ./src/core/fsm.c:875 #10 0x00007f173fb41b31 in scrc_node_6 (inst=inst@entry=0x55e35d7c9880, xua=xua@entry=0x55e35d917410, called=called@entry=0x7ffcc479bd20) at ./src/sccp_scrc.c:399 #11 0x00007f173fb426d3 in scrc_rx_mtp_xfer_ind_xua (inst=inst@entry=0x55e35d7c9880, xua=xua@entry=0x55e35d917410) at ./src/sccp_scrc.c:570 #12 0x00007f173fb3d4f5 in mtp_user_prim_cb (oph=0x55e35d91fdc8, ctx=0x55e35d7c9880) at ./src/sccp_instance.c:279 #13 0x00007f173fb51ebb in deliver_to_mtp_user (xua=0x55e35d918820, osu=0x55e35d9093a0) at ./src/ss7_hmrt.c:106 #14 hmdt_message_for_distribution (xua=0x55e35d918820, inst=0x55e35d909a00) at ./src/ss7_hmrt.c:151 #15 m3ua_hmdc_rx_from_l2 (inst=0x55e35d909a00, xua=xua@entry=0x55e35d918820) at ./src/ss7_hmrt.c:246 #16 0x00007f173fb36277 in m3ua_rx_xfer (xua=0x55e35d918820, asp=0x55e35d909cf0) at ./src/m3ua.c:657 #17 m3ua_rx_msg (asp=asp@entry=0x55e35d909cf0, msg=msg@entry=0x55e35d91f050) at ./src/m3ua.c:817 #18 0x00007f173fb4ccd3 in xua_cli_read_cb (conn=<optimized out>, res=144, msg=0x55e35d91f050) at ./src/ss7_asp.c:1321 #19 0x00007f173f64dae2 in ?? () from /lib/x86_64-linux-gnu/libosmonetif.so.11 #20 0x00007f173fbfa950 in iofd_poll_ofd_cb_recvmsg_sendmsg (ofd=0x55e35d8889e0, what=1) at ./src/core/osmo_io_poll.c:84 #21 0x00007f173fbfac1e in iofd_poll_ofd_cb_dispatch (ofd=<optimized out>, what=<optimized out>) at ./src/core/osmo_io_poll.c:136 #22 0x00007f173fbfcd54 in poll_disp_fds (n_fd=<optimized out>) at ./src/core/select.c:419 #23 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457 #24 0x00007f173fbfce26 in osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:496 #25 0x000055e35170d5e6 in main (argc=1, argv=<optimized out>) at ./src/sgsn/sgsn_main.c:505 [testenv][generic] Stopping podman container [testenv][generic] + ['podman', 'kill', 'testenv-sgsn-generic-osmocom-nightly-20250715-0948-92867e95-0'] testenv-sgsn-generic-osmocom-nightly-20250715-0948-92867e95-0 [testenv][generic] Stopping testsuite (2627783) [testenv] feed_watchdog_loop: podman container has stopped [testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2781/artifact/logs/  + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* load average: 2.72, 4.31, 3.58 + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.