15 Jul
2025
15 Jul
'25
11:11 a.m.
See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/502/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
[Pau Espin Pedrol] hnbgw: Use static sigtran config also in osmo-hnbgw-with-pfcp.cfg
[Oliver Smith] smlc: fix running against latest
------------------------------------------
[...truncated 3.31 MiB...]
[38;5;49m20250715151126844 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250715151126844 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 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)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250715151126844 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[1;33m20250715151126844 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Rx CL IM (Reset)
(ranap_common_cn.c:436)[0;m
[1;33m20250715151126844 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Decoding message
RANAP_ResetIEs (ranap_decoder.c:2011) (ranap_decoder.c:2011)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250715151126844 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250715151126844 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250715151126844 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=6
(m3ua.c:642)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250715151126844 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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=6 -> eSLS=6: picked Normal Route via
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250715151126844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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 06 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)[0;m
SGSN_Test_0-RAN(1424)@1d898911cdb7: RANAP: Received RESET-ACK in response to RESET,
we're ready to go!
TC_iu_attach-BVCI196(1411)@1d898911cdb7: Adding Client=TC_iu_attach(1430),
IMSI='262420000001001'H, TLLI='DC35EAA5'O, index=0
SGSN_Test-GSUP(1428)@1d898911cdb7: Created GsupExpect[0] for "262420000001001"
to be handled at TC_iu_attach(1430)
TC_iu_attach(1430)@1d898911cdb7: 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 := 'FCE0'O, rac := 'B9'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)@1d898911cdb7: Added conn table entry 0TC_iu_attach(1430)4188795
SGSN_Test_0-SCCP(1423)@1d898911cdb7: First idle individual index:0
SGSN_Test_0-RAN(1424)@1d898911cdb7: patch: N(SD) for ConnIdx 0 set to 1
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250715151127192 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250715151127192 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250715151127192 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 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)[0;m
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203)[0;m
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250715151127192 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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 3f ea 7b 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 fc
e0 b9 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)[0;m
[38;5;43m20250715151127193 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250715151127193 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250715151127193 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250715151127193 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=1
(m3ua.c:642)[0;m
[38;5;43m20250715151127193 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250715151127193 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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=1 -> eSLS=1: picked Normal Route via
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)[0;m
[38;5;43m20250715151127193 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
Found route for dpc=195=0.24.3: pc=195=0.24.3 mask=0x3fff=7.255.7 prio=5 dyn via AS
virt-rnc0 proto=m3ua (ss7_hmrt.c:193)[0;m
[38;5;43m20250715151127193 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203)[0;m
[38;5;43m20250715151127193 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250715151127193 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) Tx 48 bytes: [L2]> 01 00 01 01 00 00 00 30 02 10 00 20 00 00 00 bc 00
00 00 c3 03 00 00 01 02 3f ea 7b 00 00 01 02 01 03 04 43 bc 00 8e 00 00 06 00 08 00 00 00
02 (ss7_asp.c:1383)[0;m
[1;33m20250715151127193 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Rx CO IM (Initial
UE Message) (ranap_common_cn.c:41)[0;m
[1;33m20250715151127193 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Decoding message
RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)[0;m
20250715151127193 [1;33mDRANAP[0;m [1;33mNOTICE[0;m handle_co_initial(dir=1, proc=19)
(iu_client.c:660)
20250715151127193 [1;33mDRANAP[0;m [1;33mNOTICE[0;m New RNC 262-42-2342 at
RI=2,PC=195,SSN=142 (iu_client.c:244)
20250715151127193 [1;33mDRANAP[0;m [1;33mNOTICE[0;m RNC 262-42-2342: new LAC/RAC
262-42-4660-0 (iu_client.c:356)
SGSN_Test_0-SCCP(1423)@1d898911cdb7: { messageType := 2, destLocRef := '3FEA7B'O,
sourceLocRef := '000001'O, protClass := { class := '0010'B,
messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit,
calledPAddress := { paramName := 3, paramLength := 4, addr := '43BC008E'O }, data
:= omit, importance := omit }, eop := { paramName := 0 } }
SGSN_Test_0-SCCP(1423)@1d898911cdb7: Session index based on local reference:0
TC_iu_attach(1430)@1d898911cdb7: GSUP sends 2G and 3G auth tuples{ tag :=
OSMO_GSUP_AUTH_TUPLE_IE (3), len := 0, val := { auth_tuple := { { tag := OSMO_GSUP_RAND_IE
(32), len := 0, val := { rand := 'F9537230A816CAB45210ED476B77CF79'O } }, { tag :=
OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := 'CF95BA2F'O } }, { tag :=
OSMO_GSUP_KC_IE (34), len := 0, val := { kc := '8CE4A685FACE78D5'O } }, { tag :=
OSMO_GSUP_IK_IE (35), len := 0, val := { ik := '7B1CB507467E63283E0D03C71D53A7C7'O
} }, { tag := OSMO_GSUP_CK_IE (36), len := 0, val := { ck :=
'CCE5070BF5F069DC5B234473524FA169'O } }, { tag := OSMO_GSUP_AUTN_IE (37), len :=
0, val := { autn := '652E9E635AA68D940FE70C7300F733BD'O } }, { tag :=
OSMO_GSUP_RES_IE (39), len := 0, val := { res := 'A89817638F00B9AB'O } } } } }
[38;5;43m20250715151127471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: Tx HEARTBEAT (0 unacked) (xua_asp_fsm.c:419)[0;m
[38;5;43m20250715151127471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) Tx 8 bytes: [L2]> 01 00 03 03 00 00 00 08 (ss7_asp.c:1383)[0;m
[38;5;23m20250715151127796 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
SRVCONN(sgsn0-0,r=127.0.0.10:1905<->l=127.0.0.200:2905) connection closed with
client (stream_srv.c:821)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250715151127796 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250715151127796 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: COMM_LOST (ss7_asp.c:1008)[0;m
[38;5;23m20250715151127796 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m
SRVCONN(sgsn0-0,r=127.0.0.10:1905<->l=127.0.0.200:2905) lost connection with client
(-104) (stream_srv.c:818)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;43m20250715151127796 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
sgsn0-0: connection closed (ss7_asp.c:1334)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:787)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:401)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:598)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all
non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20250715151127796 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151127796 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
Segmentation fault (core dumped)
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication
(xua_asp_fsm.c:122)[0;m
[38;5;43m20250715151127796 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication
(xua_asp_fsm.c:122)[0;m
SGSN_Test-GSUP-IPA(1427)@1d898911cdb7: IPA: Closed
SGSN_Test-GSUP(1428)@1d898911cdb7: setverdict(fail): none -> fail reason: "GSUP
Connection Lost", new component reason: "GSUP Connection Lost"
MC@1d898911cdb7: Test Component 1428 has requested to stop MTC. Terminating current
testcase execution.
SGSN_Test-GSUP(1428)@1d898911cdb7: Final verdict of PTC: fail reason: "GSUP
Connection Lost"
SGSN_Test-GSUP-IPA(1427)@1d898911cdb7: Final verdict of PTC: none
-NSVCI99(1422)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(1408)@1d898911cdb7: Final verdict of PTC: none
-NSVCI97(1412)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test_0-SCCP(1423)@1d898911cdb7: Final verdict of PTC: none
TC_iu_attach-BVCI196(1411)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(1414)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-Gb1-NS1(1413)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test_0-RAN(1424)@1d898911cdb7: Final verdict of PTC: none
TC_iu_attach-BVCI210(1416)@1d898911cdb7: Final verdict of PTC: none
TC_iu_attach-BVCI220(1421)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-Gb2-NS2(1418)@1d898911cdb7: Final verdict of PTC: none
[38;5;23m20250715151127801 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
virt-rnc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250715151127801 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250715151127801 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023)[0;m
[38;5;23m20250715151127801 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
virt-rnc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250715151127801 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250715151127801 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008)[0;m
[38;5;23m20250715151127801 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
SRVCONN(virt-rnc0-0,r=127.0.0.104:23908<->l=127.0.0.200:2905) connection closed with
client (stream_srv.c:821)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;43m20250715151127801 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
virt-rnc0-0: connection closed (ss7_asp.c:1334)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:787)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:401)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:598)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all
non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20250715151127801 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151127801 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication
(xua_asp_fsm.c:122)[0;m
[38;5;43m20250715151127801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication
(xua_asp_fsm.c:122)[0;m
SGSN_Test-Gb2-BSSGP2(1419)@1d898911cdb7: Final verdict of PTC: none
-NSVCI99-provIP(1420)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test_0-M3UA(1425)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(1409)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-RNC_GTP(1426)@1d898911cdb7: Final verdict of PTC: none
-NSVCI97-provIP(1410)@1d898911cdb7: Final verdict of PTC: none
-NSVCI98-provIP(1415)@1d898911cdb7: Final verdict of PTC: none
-NSVCI98(1417)@1d898911cdb7: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(1429)@1d898911cdb7: Final verdict of PTC: none
TC_iu_attach(1430)@1d898911cdb7: Final verdict of PTC: none
MTC@1d898911cdb7: Setting final verdict of the test case.
MTC@1d898911cdb7: Local verdict of MTC: none
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-Gb0-NS0(1408): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(1409): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC -NSVCI97-provIP(1410): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC TC_iu_attach-BVCI196(1411): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC -NSVCI97(1412): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-Gb1-NS1(1413): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(1414): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC -NSVCI98-provIP(1415): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC TC_iu_attach-BVCI210(1416): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC -NSVCI98(1417): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-Gb2-NS2(1418): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(1419): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC -NSVCI99-provIP(1420): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC TC_iu_attach-BVCI220(1421): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC -NSVCI99(1422): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test_0-SCCP(1423): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test_0-RAN(1424): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test_0-M3UA(1425): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-RNC_GTP(1426): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-GSUP-IPA(1427): none (none -> none)
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-GSUP(1428): fail (none -> fail)
reason: "GSUP Connection Lost"
MTC@1d898911cdb7: Local verdict of PTC SGSN_Test-GGSN_GTP(1429): none (fail -> fail)
MTC@1d898911cdb7: Local verdict of PTC TC_iu_attach(1430): none (fail -> fail)
MTC@1d898911cdb7: Test case TC_iu_attach finished. Verdict: fail reason: GSUP Connection
Lost
MTC@1d898911cdb7: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach fail'.
Tue Jul 15 15:11:27 UTC 2025
[1;31m------ SGSN_Tests_Iu.TC_iu_attach fail ------[0m
Saving talloc report from 127.0.0.10:4245 to SGSN_Tests_Iu.TC_iu_attach.talloc
[1;34m[testenv][generic] Looking for a coredump on lxc host[0m
[0;94m[testenv][generic] + ['ip', '-j', '-o', '-4',
'addr', 'show', 'dev', 'eth0'][0m
[0;94m[testenv][generic] Coredump found and copied to log dir[0m
[1;91m[testenv][generic] sgsn unexpected exit during SGSN_Tests_Iu.TC_iu_attach!
rc=0[0m
[0;94m[testenv][generic] Waiting until test has stopped...[0m
[38;5;43m20250715151129797 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m
[38;5;43m20250715151129797 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:657)[0;m
[38;5;43m20250715151129797 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:667)[0;m
[38;5;43m20250715151129801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m
[38;5;43m20250715151129801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: T(r) expired; dropping queued messages
(xua_as_fsm.c:657)[0;m
[38;5;43m20250715151129801 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:667)[0;m
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=125836)
Waiting for packet dumper to finish... 1 (prev_count=125836, count=126620)
MTC@1d898911cdb7: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach fail' was executed successfully (exit status: 0).
MTC@1d898911cdb7: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-start.sh>
SGSN_Tests_Iu.TC_iu_attach_encr'.
------ SGSN_Tests_Iu.TC_iu_attach_encr ------
Tue Jul 15 15:11:32 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/502/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/502/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][generic] Running gdb to get a backtrace[0m
[0;94m[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-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-sgsn-test-io_uring/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/sgsn>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin',
'-e', 'HOME=/home/osmocom-build', '-t',
'testenv-sgsn-generic-osmocom-nightly-20250715-1458-ae32c53d-0', 'sh',
'-c', 'gdb --batch /usr/bin/osmo-sgsn
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/502/artifact/logs/core>
-ex bt | tee
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/502/artifact/logs/core.backtrace'][0m>
warning: Can't open file anon_inode:[io_uring] which was expanded to
anon_inode:[io_uring] during file-backed mapping note processing
warning: Can't open file anon_inode:[io_uring] which was expanded to
anon_inode:[io_uring] during file-backed mapping note processing
[New LWP 9231]
196 ./src/gsm/gsm48.c: No such file or directory[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 0x00007f5337284ef7 in osmo_rai_name_buf (buf=0x7f533625a0b3
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at
./src/gsm/gsm48.c:196
.
#0 0x00007f5337284ef7 in osmo_rai_name_buf (buf=0x7f533625a0b3
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at
./src/gsm/gsm48.c:196
#1 0x00007f5337284f85 in osmo_rai_name (rai=rai@entry=0x20) at ./src/gsm/gsm48.c:209
#2 0x000055ad6e208ba1 in sgsn_ranap_iu_event (ctx=0x0, type=RANAP_IU_EVENT_NEW_AREA,
data=0x7ffda87cc5f0) at ./src/sgsn/gprs_ranap.c:147
#3 0x00007f533707fea4 in global_iu_event_new_area (rai=0x7ffda87cc5e6,
rnc_id=0x7ffda87cc5d4) at ./src/iu_client.c:153
#4 iu_rnc_register (addr=0x7ffda87cc5e6, rai=0x7ffda87cc5e6, rnc_id=0x7ffda87cc5d4) at
./src/iu_client.c:360
#5 ranap_handle_co_initial_ue (ies=0x55ad6e824da8, ctx=0x7ffda87cc5e6) at
./src/iu_client.c:500
#6 cn_ranap_handle_co_initial (ctx=ctx@entry=0x7ffda87ccad0,
message=message@entry=0x7ffda87cc760) at ./src/iu_client.c:670
#7 0x00007f533707d9f5 in ranap_cn_rx_co (cb=cb@entry=0x7f533707fa30
<cn_ranap_handle_co_initial>, priv=priv@entry=0x7ffda87ccad0, data=<optimized
out>, len=<optimized out>) at ./src/ranap_common_cn.c:422
#8 0x00007f5337080c5d in sccp_sap_up (oph=0x55ad6e8bcd28, _scu=0x55ad6e8a7420) at
./src/iu_client.c:1041
#9 0x00007f533722a1b0 in _osmo_fsm_inst_dispatch (fi=0x55ad6e8b3e40, event=5,
data=0x55ad6e8b69c0, file=0x7f53371a9648 "sccp_scoc.c", line=820) at
./src/core/fsm.c:875
#10 0x00007f533717eb31 in scrc_node_6 (inst=inst@entry=0x55ad6e765890,
xua=xua@entry=0x55ad6e8b69c0, called=called@entry=0x7ffda87ccbe0) at
./src/sccp_scrc.c:399
#11 0x00007f533717f6d3 in scrc_rx_mtp_xfer_ind_xua (inst=inst@entry=0x55ad6e765890,
xua=xua@entry=0x55ad6e8b69c0) at ./src/sccp_scrc.c:570
#12 0x00007f533717a4f5 in mtp_user_prim_cb (oph=0x55ad6e8abd78, ctx=0x55ad6e765890) at
./src/sccp_instance.c:279
#13 0x00007f533718eebb in deliver_to_mtp_user (xua=0x55ad6e8aed10, osu=0x55ad6e8a53b0) at
./src/ss7_hmrt.c:106
#14 hmdt_message_for_distribution (xua=0x55ad6e8aed10, inst=0x55ad6e8a5a10) at
./src/ss7_hmrt.c:151
#15 m3ua_hmdc_rx_from_l2 (inst=0x55ad6e8a5a10, xua=xua@entry=0x55ad6e8aed10) at
./src/ss7_hmrt.c:246
#16 0x00007f5337173277 in m3ua_rx_xfer (xua=0x55ad6e8aed10, asp=0x55ad6e8a5d00) at
./src/m3ua.c:657
#17 m3ua_rx_msg (asp=asp@entry=0x55ad6e8a5d00, msg=msg@entry=0x55ad6e8c05a0) at
./src/m3ua.c:817
#18 0x00007f5337189cd3 in xua_cli_read_cb (conn=<optimized out>, res=144,
msg=0x55ad6e8c05a0) at ./src/ss7_asp.c:1321
#19 0x00007f5336c8aae2 in ?? () from /lib/x86_64-linux-gnu/libosmonetif.so.11
#20 0x00007f533724eb3d in iofd_uring_handle_recv (rc=<optimized out>,
msghdr=0x55ad6e8acde0) at ./src/core/osmo_io_uring.c:193
#21 iofd_uring_handle_completion (res=<optimized out>, msghdr=0x55ad6e8acde0) at
./src/core/osmo_io_uring.c:243
#22 iofd_uring_cqe (ring=<optimized out>) at ./src/core/osmo_io_uring.c:285
#23 iofd_uring_poll_cb (ofd=<optimized out>, what=1) at
./src/core/osmo_io_uring.c:80
#24 0x00007f5337239d54 in poll_disp_fds (n_fd=<optimized out>) at
./src/core/select.c:419
#25 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457
#26 0x00007f5337239e26 in osmo_select_main (polling=polling@entry=0) at
./src/core/select.c:496
#27 0x000055ad6e1da5e6 in main (argc=1, argv=<optimized out>) at
./src/sgsn/sgsn_main.c:505
MTC@1d898911cdb7: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-start.sh>
SGSN_Tests_Iu.TC_iu_attach_encr' was executed successfully (exit status: 0).
MTC@1d898911cdb7: Test case TC_iu_attach_encr started.
MTC@1d898911cdb7: setverdict(fail): none -> fail reason: ""VTY Timeout for
prompt: enable"", new component reason: ""VTY Timeout for prompt:
enable""
MTC@1d898911cdb7: Setting final verdict of the test case.
MTC@1d898911cdb7: Local verdict of MTC: fail reason: ""VTY Timeout for prompt:
enable""
MTC@1d898911cdb7: No PTCs were created.
MTC@1d898911cdb7: Test case TC_iu_attach_encr finished. Verdict: fail reason: "VTY
Timeout for prompt: enable"
MTC@1d898911cdb7: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach_encr fail'.
Tue Jul 15 15:11:35 UTC 2025
[1;31m------ SGSN_Tests_Iu.TC_iu_attach_encr fail ------[0m
Saving talloc report from 127.0.0.10:4245 to SGSN_Tests_Iu.TC_iu_attach_encr.talloc
[1;34m[testenv][generic] Stopping podman container[0m
[0;94m[testenv][generic] + ['podman', 'kill',
'testenv-sgsn-generic-osmocom-nightly-20250715-1458-ae32c53d-0'][0m
testenv-sgsn-generic-osmocom-nightly-20250715-1458-ae32c53d-0
[1;34m[testenv][generic] Stopping testsuite (2474723)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][generic] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/502/artifa… [0m
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 11.65, 10.84, 8.32[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
16 Jul
16 Jul
11:11 a.m.
New subject: Build failed in Jenkins: ttcn3-sgsn-test-io_uring #503
See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/503/display/redirect?page=changes>
Changes:
[lynxis] SGSN: Iu: use correct service request type
------------------------------------------
[...truncated 3.31 MiB...]
SGSN_Test-GSUP-IPA(1427)@c39acf5ab00b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
SGSN_Test-GSUP-IPA(1427)@c39acf5ab00b: 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 } } } }
[38;5;39m20250716151129572 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP
receiving PONG (gsup_client.c:228)[0;m
SGSN_Test-GSUP-IPA(1427)@c39acf5ab00b: 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)@c39acf5ab00b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
SGSN_Test-GSUP-IPA(1427)@c39acf5ab00b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250716151132021 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250716151132021 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250716151132021 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 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)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250716151132021 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[1;33m20250716151132021 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Rx CL IM (Reset)
(ranap_common_cn.c:436)[0;m
[1;33m20250716151132021 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Decoding message
RANAP_ResetIEs (ranap_decoder.c:2011) (ranap_decoder.c:2011)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250716151132021 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250716151132021 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250716151132021 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=3
(m3ua.c:642)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250716151132021 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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=3 -> eSLS=3: picked Normal Route via
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250716151132021 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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 03 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)[0;m
SGSN_Test_0-RAN(1424)@c39acf5ab00b: RANAP: Received RESET-ACK in response to RESET,
we're ready to go!
TC_iu_attach-BVCI196(1411)@c39acf5ab00b: Adding Client=TC_iu_attach(1430),
IMSI='262420000001001'H, TLLI='E9B8C69D'O, index=0
SGSN_Test-GSUP(1428)@c39acf5ab00b: Created GsupExpect[0] for "262420000001001"
to be handled at TC_iu_attach(1430)
TC_iu_attach(1430)@c39acf5ab00b: 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 := '376A'O ("7j"), rac := 'CD'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)@c39acf5ab00b: Added conn table entry 0TC_iu_attach(1430)5960800
SGSN_Test_0-SCCP(1423)@c39acf5ab00b: First idle individual index:0
SGSN_Test_0-RAN(1424)@c39acf5ab00b: patch: N(SD) for ConnIdx 0 set to 1
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250716151132150 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250716151132150 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250716151132150 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 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)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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 5a f4 60 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 37
6a cd 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)[0;m
[1;33m20250716151132150 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Rx CO IM (Initial
UE Message) (ranap_common_cn.c:41)[0;m
[1;33m20250716151132150 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Decoding message
RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)[0;m
20250716151132150 [1;33mDRANAP[0;m [1;33mNOTICE[0;m handle_co_initial(dir=1, proc=19)
(iu_client.c:660)
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250716151132150 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250716151132150 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250716151132150 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=1
(m3ua.c:642)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250716151132150 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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=1 -> eSLS=1: picked Normal Route via
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
Found route for dpc=195=0.24.3: pc=195=0.24.3 mask=0x3fff=7.255.7 prio=5 dyn via AS
virt-rnc0 proto=m3ua (ss7_hmrt.c:193)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203)[0;m
20250716151132150 [1;33mDRANAP[0;m [1;33mNOTICE[0;m New RNC 262-42-2342 at
RI=2,PC=195,SSN=142 (iu_client.c:244)
20250716151132150 [1;33mDRANAP[0;m [1;33mNOTICE[0;m RNC 262-42-2342: new LAC/RAC
262-42-4660-0 (iu_client.c:356)
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250716151132150 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) Tx 48 bytes: [L2]> 01 00 01 01 00 00 00 30 02 10 00 20 00 00 00 bc 00
00 00 c3 03 00 00 01 02 5a f4 60 00 00 01 02 01 03 04 43 bc 00 8e 00 00 06 00 08 00 00 00
02 (ss7_asp.c:1383)[0;m
SGSN_Test_0-SCCP(1423)@c39acf5ab00b: { messageType := 2, destLocRef := '5AF460'O,
sourceLocRef := '000001'O, protClass := { class := '0010'B,
messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit,
calledPAddress := { paramName := 3, paramLength := 4, addr := '43BC008E'O }, data
:= omit, importance := omit }, eop := { paramName := 0 } }
SGSN_Test_0-SCCP(1423)@c39acf5ab00b: Session index based on local reference:0
TC_iu_attach(1430)@c39acf5ab00b: GSUP sends 2G and 3G auth tuples{ tag :=
OSMO_GSUP_AUTH_TUPLE_IE (3), len := 0, val := { auth_tuple := { { tag := OSMO_GSUP_RAND_IE
(32), len := 0, val := { rand := '42646A0C76F6C573B9D985B8259A3AE3'O } }, { tag :=
OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := '988A3250'O } }, { tag :=
OSMO_GSUP_KC_IE (34), len := 0, val := { kc := 'B4FC4D61EBC0FD67'O } }, { tag :=
OSMO_GSUP_IK_IE (35), len := 0, val := { ik := '11DD14E0C3F1FE13AB1A7C7BE7F7BBBB'O
} }, { tag := OSMO_GSUP_CK_IE (36), len := 0, val := { ck :=
'70D3B97D863EDB13B9A87C5BDA3C9C87'O } }, { tag := OSMO_GSUP_AUTN_IE (37), len :=
0, val := { autn := 'F067EF415B80E7A999C2019F8E5C7464'O } }, { tag :=
OSMO_GSUP_RES_IE (39), len := 0, val := { res := '6D5C8C3C29FBBD4D'O } } } } }
[38;5;23m20250716151132660 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
[38;5;43m20250716151132660 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250716151132660 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250716151132660 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023)[0;m
[38;5;23m20250716151132661 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250716151132661 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250716151132661 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008)[0;m
[38;5;23m20250716151132661 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
SRVCONN(sgsn0-0,r=127.0.0.10:1905<->l=127.0.0.200:2905) connection closed with
client (stream_srv.c:821)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;43m20250716151132661 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
sgsn0-0: connection closed (ss7_asp.c:1334)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:790)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:401)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:598)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all
non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20250716151132661 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132661 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
Segmentation fault (core dumped)
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication
(xua_asp_fsm.c:122)[0;m
[38;5;43m20250716151132661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication
(xua_asp_fsm.c:122)[0;m
SGSN_Test-GSUP-IPA(1427)@c39acf5ab00b: IPA: Closed
SGSN_Test-GSUP(1428)@c39acf5ab00b: setverdict(fail): none -> fail reason: "GSUP
Connection Lost", new component reason: "GSUP Connection Lost"
MC@c39acf5ab00b: Test Component 1428 has requested to stop MTC. Terminating current
testcase execution.
SGSN_Test-GSUP(1428)@c39acf5ab00b: Final verdict of PTC: fail reason: "GSUP
Connection Lost"
-NSVCI97(1412)@c39acf5ab00b: Final verdict of PTC: none
-NSVCI98(1417)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-GSUP-IPA(1427)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(1408)@c39acf5ab00b: Final verdict of PTC: none
[38;5;23m20250716151132663 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
virt-rnc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250716151132663 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250716151132663 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023)[0;m
[38;5;23m20250716151132663 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
virt-rnc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250716151132663 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250716151132663 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008)[0;m
[38;5;23m20250716151132663 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
SRVCONN(virt-rnc0-0,r=127.0.0.104:23908<->l=127.0.0.200:2905) connection closed with
client (stream_srv.c:821)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;43m20250716151132663 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
virt-rnc0-0: connection closed (ss7_asp.c:1334)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:790)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:401)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:598)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all
non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20250716151132663 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132663 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication
(xua_asp_fsm.c:122)[0;m
[38;5;43m20250716151132663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication
(xua_asp_fsm.c:122)[0;m
SGSN_Test_0-RAN(1424)@c39acf5ab00b: Final verdict of PTC: none
TC_iu_attach-BVCI196(1411)@c39acf5ab00b: Final verdict of PTC: none
-NSVCI99(1422)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test_0-SCCP(1423)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(1414)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-Gb2-NS2(1418)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test_0-M3UA(1425)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-Gb1-NS1(1413)@c39acf5ab00b: Final verdict of PTC: none
TC_iu_attach-BVCI210(1416)@c39acf5ab00b: Final verdict of PTC: none
-NSVCI99-provIP(1420)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(1409)@c39acf5ab00b: Final verdict of PTC: none
TC_iu_attach-BVCI220(1421)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(1429)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-RNC_GTP(1426)@c39acf5ab00b: Final verdict of PTC: none
SGSN_Test-Gb2-BSSGP2(1419)@c39acf5ab00b: Final verdict of PTC: none
TC_iu_attach(1430)@c39acf5ab00b: Final verdict of PTC: none
-NSVCI98-provIP(1415)@c39acf5ab00b: Final verdict of PTC: none
-NSVCI97-provIP(1410)@c39acf5ab00b: Final verdict of PTC: none
MTC@c39acf5ab00b: Setting final verdict of the test case.
MTC@c39acf5ab00b: Local verdict of MTC: none
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-Gb0-NS0(1408): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(1409): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC -NSVCI97-provIP(1410): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC TC_iu_attach-BVCI196(1411): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC -NSVCI97(1412): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-Gb1-NS1(1413): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(1414): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC -NSVCI98-provIP(1415): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC TC_iu_attach-BVCI210(1416): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC -NSVCI98(1417): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-Gb2-NS2(1418): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(1419): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC -NSVCI99-provIP(1420): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC TC_iu_attach-BVCI220(1421): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC -NSVCI99(1422): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test_0-SCCP(1423): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test_0-RAN(1424): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test_0-M3UA(1425): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-RNC_GTP(1426): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-GSUP-IPA(1427): none (none -> none)
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-GSUP(1428): fail (none -> fail)
reason: "GSUP Connection Lost"
MTC@c39acf5ab00b: Local verdict of PTC SGSN_Test-GGSN_GTP(1429): none (fail -> fail)
MTC@c39acf5ab00b: Local verdict of PTC TC_iu_attach(1430): none (fail -> fail)
MTC@c39acf5ab00b: Test case TC_iu_attach finished. Verdict: fail reason: GSUP Connection
Lost
MTC@c39acf5ab00b: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach fail'.
Wed Jul 16 15:11:32 UTC 2025
[1;31m------ SGSN_Tests_Iu.TC_iu_attach fail ------[0m
Saving talloc report from 127.0.0.10:4245 to SGSN_Tests_Iu.TC_iu_attach.talloc
[1;34m[testenv][generic] Looking for a coredump on lxc host[0m
[0;94m[testenv][generic] + ['ip', '-j', '-o', '-4',
'addr', 'show', 'dev', 'eth0'][0m
[0;94m[testenv][generic] Coredump found and copied to log dir[0m
[1;91m[testenv][generic] sgsn unexpected exit during SGSN_Tests_Iu.TC_iu_attach!
rc=0[0m
[0;94m[testenv][generic] Waiting until test has stopped...[0m
[38;5;43m20250716151134661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m
[38;5;43m20250716151134661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:657)[0;m
[38;5;43m20250716151134661 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:667)[0;m
[38;5;43m20250716151134663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m
[38;5;43m20250716151134663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: T(r) expired; dropping queued messages
(xua_as_fsm.c:657)[0;m
[38;5;43m20250716151134663 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:667)[0;m
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=132460)
Waiting for packet dumper to finish... 1 (prev_count=132460, count=134028)
MTC@c39acf5ab00b: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach fail' was executed successfully (exit status: 0).
MTC@c39acf5ab00b: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-start.sh>
SGSN_Tests_Iu.TC_iu_attach_encr'.
------ SGSN_Tests_Iu.TC_iu_attach_encr ------
Wed Jul 16 15:11:38 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/503/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/503/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][generic] Running gdb to get a backtrace[0m
[0;94m[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-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-sgsn-test-io_uring/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/sgsn>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin',
'-e', 'HOME=/home/osmocom-build', '-t',
'testenv-sgsn-generic-osmocom-nightly-20250716-1458-cbdb630c-0', 'sh',
'-c', 'gdb --batch /usr/bin/osmo-sgsn
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/503/artifact/logs/core>
-ex bt | tee
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/503/artifact/logs/core.backtrace'][0m>
warning: Can't open file anon_inode:[io_uring] which was expanded to
anon_inode:[io_uring] during file-backed mapping note processing
warning: Can't open file anon_inode:[io_uring] which was expanded to
anon_inode:[io_uring] during file-backed mapping note processing
[New LWP 9233]
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 0x00007f235d75cef7 in osmo_rai_name_buf (buf=0x7f235c7330b3
"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 0x00007f235d75cef7 in osmo_rai_name_buf (buf=0x7f235c7330b3
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at
./src/gsm/gsm48.c:196
#1 0x00007f235d75cf85 in osmo_rai_name (rai=rai@entry=0x20) at ./src/gsm/gsm48.c:209
#2 0x000056143a945ba1 in sgsn_ranap_iu_event (ctx=0x0, type=RANAP_IU_EVENT_NEW_AREA,
data=0x7fff6d17a790) at ./src/sgsn/gprs_ranap.c:147
#3 0x00007f235d557ef4 in global_iu_event_new_area (rai=0x7fff6d17a786,
rnc_id=0x7fff6d17a774) at ./src/iu_client.c:153
#4 iu_rnc_register (addr=0x7fff6d17a786, rai=0x7fff6d17a786, rnc_id=0x7fff6d17a774) at
./src/iu_client.c:360
#5 ranap_handle_co_initial_ue (ies=0x56143c5ddda8, ctx=0x7fff6d17a786) at
./src/iu_client.c:500
#6 cn_ranap_handle_co_initial (ctx=ctx@entry=0x7fff6d17acf0,
message=message@entry=0x7fff6d17a900) at ./src/iu_client.c:670
#7 0x00007f235d555a45 in ranap_cn_rx_co (cb=cb@entry=0x7f235d557a80
<cn_ranap_handle_co_initial>, priv=priv@entry=0x7fff6d17acf0, data=<optimized
out>, len=<optimized out>) at ./src/ranap_common_cn.c:422
#8 0x00007f235d558cfe in sccp_sap_up (oph=0x56143c675a58, _scu=0x56143c660420) at
./src/iu_client.c:1158
#9 0x00007f235d7021b0 in _osmo_fsm_inst_dispatch (fi=0x56143c66ce40, event=5,
data=0x56143c66e810, file=0x7f235d681648 "sccp_scoc.c", line=820) at
./src/core/fsm.c:875
#10 0x00007f235d656b31 in scrc_node_6 (inst=inst@entry=0x56143c51e890,
xua=xua@entry=0x56143c66e810, called=called@entry=0x7fff6d17ae10) at
./src/sccp_scrc.c:399
#11 0x00007f235d6576d3 in scrc_rx_mtp_xfer_ind_xua (inst=inst@entry=0x56143c51e890,
xua=xua@entry=0x56143c66e810) at ./src/sccp_scrc.c:570
#12 0x00007f235d6524f5 in mtp_user_prim_cb (oph=0x56143c6744d8, ctx=0x56143c51e890) at
./src/sccp_instance.c:279
#13 0x00007f235d666ebb in deliver_to_mtp_user (xua=0x56143c66d910, osu=0x56143c65e3b0) at
./src/ss7_hmrt.c:106
#14 hmdt_message_for_distribution (xua=0x56143c66d910, inst=0x56143c65ea10) at
./src/ss7_hmrt.c:151
#15 m3ua_hmdc_rx_from_l2 (inst=0x56143c65ea10, xua=xua@entry=0x56143c66d910) at
./src/ss7_hmrt.c:246
#16 0x00007f235d64b277 in m3ua_rx_xfer (xua=0x56143c66d910, asp=0x56143c65ed00) at
./src/m3ua.c:657
#17 m3ua_rx_msg (asp=asp@entry=0x56143c65ed00, msg=msg@entry=0x56143c664af0) at
./src/m3ua.c:817
#18 0x00007f235d661cd3 in xua_cli_read_cb (conn=<optimized out>, res=144,
msg=0x56143c664af0) at ./src/ss7_asp.c:1321
#19 0x00007f235d232ae2 in ?? () from /lib/x86_64-linux-gnu/libosmonetif.so.11
#20 0x00007f235d726b3d in iofd_uring_handle_recv (rc=<optimized out>,
msghdr=0x56143c51c6b0) at ./src/core/osmo_io_uring.c:193
#21 iofd_uring_handle_completion (res=<optimized out>, msghdr=0x56143c51c6b0) at
./src/core/osmo_io_uring.c:243
#22 iofd_uring_cqe (ring=<optimized out>) at ./src/core/osmo_io_uring.c:285
#23 iofd_uring_poll_cb (ofd=<optimized out>, what=1) at
./src/core/osmo_io_uring.c:80
#24 0x00007f235d711d54 in poll_disp_fds (n_fd=<optimized out>) at
./src/core/select.c:419
#25 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457
#26 0x00007f235d711e26 in osmo_select_main (polling=polling@entry=0) at
./src/core/select.c:496
#27 0x000056143a9175e6 in main (argc=1, argv=<optimized out>) at
./src/sgsn/sgsn_main.c:505
MTC@c39acf5ab00b: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-start.sh>
SGSN_Tests_Iu.TC_iu_attach_encr' was executed successfully (exit status: 0).
MTC@c39acf5ab00b: Test case TC_iu_attach_encr started.
[1;34m[testenv][generic] Stopping podman container[0m
[0;94m[testenv][generic] + ['podman', 'kill',
'testenv-sgsn-generic-osmocom-nightly-20250716-1458-cbdb630c-0'][0m
testenv-sgsn-generic-osmocom-nightly-20250716-1458-cbdb630c-0
[1;34m[testenv][generic] Stopping testsuite (2542189)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][generic] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/503/artifa… [0m
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 13.93, 14.09, 9.70[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
17 Jul
17 Jul
11:11 a.m.
New subject: Build failed in Jenkins: ttcn3-sgsn-test-io_uring #504
See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/504/display/redirect>
Changes:
------------------------------------------
[...truncated 3.31 MiB...]
[38;5;23m20250717151130334 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
received ID_GET for unit ID 0/0/0 (ipa.c:617)[0;m
[38;5;39m20250717151130335 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP
receiving PONG (gsup_client.c:228)[0;m
SGSN_Test-GSUP-IPA(1427)@f7671ae0b8e9: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
SGSN_Test-GSUP-IPA(1427)@f7671ae0b8e9: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
SGSN_Test-GSUP-IPA(1427)@f7671ae0b8e9: 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)@f7671ae0b8e9: 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)@f7671ae0b8e9: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
SGSN_Test-GSUP-IPA(1427)@f7671ae0b8e9: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250717151130891 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250717151130891 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250717151130891 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 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)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250717151130891 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[1;33m20250717151130891 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Rx CL IM (Reset)
(ranap_common_cn.c:436)[0;m
[1;33m20250717151130891 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Decoding message
RANAP_ResetIEs (ranap_decoder.c:2011) (ranap_decoder.c:2011)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250717151130891 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250717151130891 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250717151130891 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=2
(m3ua.c:642)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250717151130891 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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=2 -> eSLS=2: picked Normal Route via
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250717151130891 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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 02 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)[0;m
SGSN_Test_0-RAN(1424)@f7671ae0b8e9: RANAP: Received RESET-ACK in response to RESET,
we're ready to go!
TC_iu_attach-BVCI196(1411)@f7671ae0b8e9: Adding Client=TC_iu_attach(1430),
IMSI='262420000001001'H, TLLI='D00439DD'O, index=0
SGSN_Test-GSUP(1428)@f7671ae0b8e9: Created GsupExpect[0] for "262420000001001"
to be handled at TC_iu_attach(1430)
TC_iu_attach(1430)@f7671ae0b8e9: 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 := '82AF'O, rac := '0C'O ("\f") }, 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)@f7671ae0b8e9: Added conn table entry 0TC_iu_attach(1430)4230452
SGSN_Test_0-SCCP(1423)@f7671ae0b8e9: First idle individual index:0
SGSN_Test_0-RAN(1424)@f7671ae0b8e9: patch: N(SD) for ConnIdx 0 set to 1
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250717151131369 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250717151131369 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250717151131369 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 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)[0;m
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=188=0.23.4 (ss7_hmrt.c:203)[0;m
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250717151131369 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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 8d 34 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 82
af 0c 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)[0;m
[38;5;43m20250717151131370 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;49m20250717151131370 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) Received M3UA Message (XFER:DATA) (m3ua.c:797)[0;m
[38;5;49m20250717151131370 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)[0;m
[38;5;49m20250717151131370 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0:
ASP(sgsn0-0) m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=195=0.24.3 sls=1
(m3ua.c:642)[0;m
[38;5;43m20250717151131370 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not local, message is for routing
(ss7_hmrt.c:248)[0;m
[38;5;43m20250717151131370 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 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=1 -> eSLS=1: picked Normal Route via
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)[0;m
[38;5;43m20250717151131370 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
Found route for dpc=195=0.24.3: pc=195=0.24.3 mask=0x3fff=7.255.7 prio=5 dyn via AS
virt-rnc0 proto=m3ua (ss7_hmrt.c:193)[0;m
[38;5;43m20250717151131370 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
rt->dest.as proto is M3UA for dpc=195=0.24.3 (ss7_hmrt.c:203)[0;m
[38;5;43m20250717151131370 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:562)[0;m
[38;5;43m20250717151131370 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) Tx 48 bytes: [L2]> 01 00 01 01 00 00 00 30 02 10 00 20 00 00 00 bc 00
00 00 c3 03 00 00 01 02 40 8d 34 00 00 01 02 01 03 04 43 bc 00 8e 00 00 06 00 08 00 00 00
02 (ss7_asp.c:1383)[0;m
[1;33m20250717151131370 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Rx CO IM (Initial
UE Message) (ranap_common_cn.c:41)[0;m
[1;33m20250717151131370 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m Decoding message
RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)[0;m
20250717151131370 [1;33mDRANAP[0;m [1;33mNOTICE[0;m handle_co_initial(dir=1, proc=19)
(iu_client.c:660)
20250717151131370 [1;33mDRANAP[0;m [1;33mNOTICE[0;m New RNC 262-42-2342 at
RI=2,PC=195,SSN=142 (iu_client.c:244)
20250717151131370 [1;33mDRANAP[0;m [1;33mNOTICE[0;m RNC 262-42-2342: new LAC/RAC
262-42-4660-0 (iu_client.c:356)
SGSN_Test_0-SCCP(1423)@f7671ae0b8e9: { messageType := 2, destLocRef := '408D34'O,
sourceLocRef := '000001'O, protClass := { class := '0010'B,
messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit,
calledPAddress := { paramName := 3, paramLength := 4, addr := '43BC008E'O }, data
:= omit, importance := omit }, eop := { paramName := 0 } }
SGSN_Test_0-SCCP(1423)@f7671ae0b8e9: Session index based on local reference:0
TC_iu_attach(1430)@f7671ae0b8e9: GSUP sends 2G and 3G auth tuples{ tag :=
OSMO_GSUP_AUTH_TUPLE_IE (3), len := 0, val := { auth_tuple := { { tag := OSMO_GSUP_RAND_IE
(32), len := 0, val := { rand := 'AADA266DADD34B46AFBA0D6881CB7F43'O } }, { tag :=
OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := 'EBE8EAD1'O } }, { tag :=
OSMO_GSUP_KC_IE (34), len := 0, val := { kc := 'F7599D14AB813323'O } }, { tag :=
OSMO_GSUP_IK_IE (35), len := 0, val := { ik := '507E2E534D14BCF443A9CB8571E19D85'O
} }, { tag := OSMO_GSUP_CK_IE (36), len := 0, val := { ck :=
'DDC7E6BCF9D491361A311AEFAA8EE536'O } }, { tag := OSMO_GSUP_AUTN_IE (37), len :=
0, val := { autn := 'EDC5F4AC2BC2C5E86ECFDE7656EB4FD9'O } }, { tag :=
OSMO_GSUP_RES_IE (39), len := 0, val := { res := 'DEFC9784A4304390'O } } } } }
Segmentation fault (core dumped)
[38;5;23m20250717151131844 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250717151131844 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250717151131844 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023)[0;m
[38;5;23m20250717151131844 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250717151131844 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250717151131844 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008)[0;m
[38;5;23m20250717151131844 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
SRVCONN(sgsn0-0,r=127.0.0.10:1905<->l=127.0.0.200:2905) connection closed with
client (stream_srv.c:821)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;43m20250717151131844 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
sgsn0-0: connection closed (ss7_asp.c:1334)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:824)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:423)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:399)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all
non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20250717151131844 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131844 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication
(xua_asp_fsm.c:123)[0;m
[38;5;43m20250717151131844 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication
(xua_asp_fsm.c:123)[0;m
SGSN_Test-GSUP-IPA(1427)@f7671ae0b8e9: IPA: Closed
SGSN_Test-GSUP(1428)@f7671ae0b8e9: setverdict(fail): none -> fail reason: "GSUP
Connection Lost", new component reason: "GSUP Connection Lost"
MC@f7671ae0b8e9: Test Component 1428 has requested to stop MTC. Terminating current
testcase execution.
SGSN_Test-GSUP-IPA(1427)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-GSUP(1428)@f7671ae0b8e9: Final verdict of PTC: fail reason: "GSUP
Connection Lost"
-NSVCI97(1412)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test_0-RAN(1424)@f7671ae0b8e9: Final verdict of PTC: none
-NSVCI98(1418)@f7671ae0b8e9: Final verdict of PTC: none
-NSVCI99(1422)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-Gb2-NS2(1417)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test_0-SCCP(1423)@f7671ae0b8e9: Final verdict of PTC: none
[38;5;23m20250717151131848 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
virt-rnc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250717151131848 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250717151131848 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1023)[0;m
[38;5;23m20250717151131848 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
virt-rnc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m
SGSN_Test-Gb2-BSSGP2(1419)@f7671ae0b8e9: Final verdict of PTC: none
TC_iu_attach-BVCI210(1415)@f7671ae0b8e9: Final verdict of PTC: none
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)
(ss7_asp.c:1076)[0;m
[38;5;43m20250717151131848 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:1000)[0;m
[38;5;43m20250717151131848 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0:
ASP(virt-rnc0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1008)[0;m
SGSN_Test-Gb1-NS1(1413)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(1414)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(1408)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-RNC_GTP(1426)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(1429)@f7671ae0b8e9: Final verdict of PTC: none
[38;5;23m20250717151131848 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
SRVCONN(virt-rnc0-0,r=127.0.0.104:23908<->l=127.0.0.200:2905) connection closed with
client (stream_srv.c:821)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0:
ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)
(ss7_asp.c:1076)[0;m
[38;5;43m20250717151131848 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
virt-rnc0-0: connection closed (ss7_asp.c:1334)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1340)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:824)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:423)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:399)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all
non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20250717151131848 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131848 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 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)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication
(xua_asp_fsm.c:123)[0;m
[38;5;43m20250717151131848 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication
(xua_asp_fsm.c:123)[0;m
-NSVCI99-provIP(1420)@f7671ae0b8e9: Final verdict of PTC: none
-NSVCI97-provIP(1410)@f7671ae0b8e9: Final verdict of PTC: none
TC_iu_attach-BVCI196(1411)@f7671ae0b8e9: Final verdict of PTC: none
TC_iu_attach-BVCI220(1421)@f7671ae0b8e9: Final verdict of PTC: none
-NSVCI98-provIP(1416)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(1409)@f7671ae0b8e9: Final verdict of PTC: none
SGSN_Test_0-M3UA(1425)@f7671ae0b8e9: Final verdict of PTC: none
TC_iu_attach(1430)@f7671ae0b8e9: Final verdict of PTC: none
MTC@f7671ae0b8e9: Setting final verdict of the test case.
MTC@f7671ae0b8e9: Local verdict of MTC: none
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-Gb0-NS0(1408): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(1409): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC -NSVCI97-provIP(1410): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC TC_iu_attach-BVCI196(1411): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC -NSVCI97(1412): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-Gb1-NS1(1413): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(1414): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC TC_iu_attach-BVCI210(1415): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC -NSVCI98-provIP(1416): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-Gb2-NS2(1417): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC -NSVCI98(1418): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(1419): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC -NSVCI99-provIP(1420): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC TC_iu_attach-BVCI220(1421): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC -NSVCI99(1422): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test_0-SCCP(1423): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test_0-RAN(1424): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test_0-M3UA(1425): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-RNC_GTP(1426): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-GSUP-IPA(1427): none (none -> none)
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-GSUP(1428): fail (none -> fail)
reason: "GSUP Connection Lost"
MTC@f7671ae0b8e9: Local verdict of PTC SGSN_Test-GGSN_GTP(1429): none (fail -> fail)
MTC@f7671ae0b8e9: Local verdict of PTC TC_iu_attach(1430): none (fail -> fail)
MTC@f7671ae0b8e9: Test case TC_iu_attach finished. Verdict: fail reason: GSUP Connection
Lost
MTC@f7671ae0b8e9: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach fail'.
Thu Jul 17 15:11:31 UTC 2025
[1;31m------ SGSN_Tests_Iu.TC_iu_attach fail ------[0m
Saving talloc report from 127.0.0.10:4245 to SGSN_Tests_Iu.TC_iu_attach.talloc
[1;34m[testenv][generic] Looking for a coredump on lxc host[0m
[0;94m[testenv][generic] + ['ip', '-j', '-o', '-4',
'addr', 'show', 'dev', 'eth0'][0m
[0;94m[testenv][generic] Coredump found and copied to log dir[0m
[1;91m[testenv][generic] sgsn unexpected exit during SGSN_Tests_Iu.TC_iu_attach!
rc=0[0m
[0;94m[testenv][generic] Waiting until test has stopped...[0m
[38;5;43m20250717151133846 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m
[38;5;43m20250717151133846 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665)[0;m
[38;5;43m20250717151133846 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(sgsn0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675)[0;m
[38;5;43m20250717151133849 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m
[38;5;43m20250717151133849 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: T(r) expired; dropping queued messages
(xua_as_fsm.c:665)[0;m
[38;5;43m20250717151133849 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(virt-rnc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675)[0;m
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=127768)
Waiting for packet dumper to finish... 1 (prev_count=127768, count=128356)
MTC@f7671ae0b8e9: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-stop.sh>
SGSN_Tests_Iu.TC_iu_attach fail' was executed successfully (exit status: 0).
MTC@f7671ae0b8e9: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/ttcn3-tcpdump-start.sh>
SGSN_Tests_Iu.TC_iu_attach_encr'.
------ SGSN_Tests_Iu.TC_iu_attach_encr ------
Thu Jul 17 15:11:36 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/504/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/504/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][generic] Running gdb to get a backtrace[0m
[0;94m[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-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-sgsn-test-io_uring/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/sgsn>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin',
'-e', 'HOME=/home/osmocom-build', '-t',
'testenv-sgsn-generic-osmocom-nightly-20250717-1458-4f46c5d1-0', 'sh',
'-c', 'gdb --batch /usr/bin/osmo-sgsn
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/504/artifact/logs/core>
-ex bt | tee
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/504/artifact/logs/core.backtrace'][0m>
warning: Can't open file anon_inode:[io_uring] which was expanded to
anon_inode:[io_uring] during file-backed mapping note processing
warning: Can't open file anon_inode:[io_uring] which was expanded to
anon_inode:[io_uring] during file-backed mapping note processing
[New LWP 9231]
196 ./src/gsm/gsm48.c: No such file or directory.
[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 0x00007f5a7021def7 in osmo_rai_name_buf (buf=0x7f5a6f1f30b3
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at
./src/gsm/gsm48.c:196
#0 0x00007f5a7021def7 in osmo_rai_name_buf (buf=0x7f5a6f1f30b3
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at
./src/gsm/gsm48.c:196
#1 0x00007f5a7021df85 in osmo_rai_name (rai=rai@entry=0x20) at ./src/gsm/gsm48.c:209
#2 0x00005617aecc0ba1 in sgsn_ranap_iu_event (ctx=0x0, type=RANAP_IU_EVENT_NEW_AREA,
data=0x7ffd8b1f0410) at ./src/sgsn/gprs_ranap.c:147
#3 0x00007f5a70017ef4 in global_iu_event_new_area (rai=0x7ffd8b1f0406,
rnc_id=0x7ffd8b1f03f4) at ./src/iu_client.c:153
#4 iu_rnc_register (addr=0x7ffd8b1f0406, rai=0x7ffd8b1f0406, rnc_id=0x7ffd8b1f03f4) at
./src/iu_client.c:360
#5 ranap_handle_co_initial_ue (ies=0x5617b0c18478, ctx=0x7ffd8b1f0406) at
./src/iu_client.c:500
#6 cn_ranap_handle_co_initial (ctx=ctx@entry=0x7ffd8b1f0970,
message=message@entry=0x7ffd8b1f0580) at ./src/iu_client.c:670
#7 0x00007f5a70015a45 in ranap_cn_rx_co (cb=cb@entry=0x7f5a70017a80
<cn_ranap_handle_co_initial>, priv=priv@entry=0x7ffd8b1f0970, data=<optimized
out>, len=<optimized out>) at ./src/ranap_common_cn.c:422
#8 0x00007f5a70018cfe in sccp_sap_up (oph=0x5617b0c27ed8, _scu=0x5617b0c0f7a0) at
./src/iu_client.c:1158
#9 0x00007f5a701c31b0 in _osmo_fsm_inst_dispatch (fi=0x5617b0c1c1c0, event=5,
data=0x5617b0c17090, file=0x7f5a701425f8 "sccp_scoc.c", line=820) at
./src/core/fsm.c:875
#10 0x00007f5a70116af1 in scrc_node_6 (inst=inst@entry=0x5617b0acb890,
xua=xua@entry=0x5617b0c17090, called=called@entry=0x7ffd8b1f0a90) at
./src/sccp_scrc.c:401
#11 0x00007f5a70117693 in scrc_rx_mtp_xfer_ind_xua (inst=inst@entry=0x5617b0acb890,
xua=xua@entry=0x5617b0c17090) at ./src/sccp_scrc.c:572
#12 0x00007f5a70112535 in mtp_user_prim_cb (oph=0x5617b0c140f8, ctx=0x5617b0acb890) at
./src/sccp_instance.c:279
#13 0x00007f5a701272cb in deliver_to_mtp_user (xua=0x5617b0c24cf0, osu=0x5617b0c0d730) at
./src/ss7_hmrt.c:106
#14 hmdt_message_for_distribution (xua=0x5617b0c24cf0, inst=0x5617b0c0dd90) at
./src/ss7_hmrt.c:151
#15 m3ua_hmdc_rx_from_l2 (inst=0x5617b0c0dd90, xua=xua@entry=0x5617b0c24cf0) at
./src/ss7_hmrt.c:246
#16 0x00007f5a7010b287 in m3ua_rx_xfer (xua=0x5617b0c24cf0, asp=0x5617b0c0e080) at
./src/m3ua.c:657
#17 m3ua_rx_msg (asp=asp@entry=0x5617b0c0e080, msg=msg@entry=0x5617b0c28950) at
./src/m3ua.c:817
#18 0x00007f5a70121c93 in xua_cli_read_cb (conn=<optimized out>, res=144,
msg=0x5617b0c28950) at ./src/ss7_asp.c:1321
#19 0x00007f5a6f9edae2 in ?? () from /lib/x86_64-linux-gnu/libosmonetif.so.11
#20 0x00007f5a701e7b3d in iofd_uring_handle_recv (rc=<optimized out>,
msghdr=0x5617b0c13ab0) at ./src/core/osmo_io_uring.c:193
#21 iofd_uring_handle_completion (res=<optimized out>, msghdr=0x5617b0c13ab0) at
./src/core/osmo_io_uring.c:243
#22 iofd_uring_cqe (ring=<optimized out>) at ./src/core/osmo_io_uring.c:285
#23 iofd_uring_poll_cb (ofd=<optimized out>, what=1) at
./src/core/osmo_io_uring.c:80
#24 0x00007f5a701d2d54 in poll_disp_fds (n_fd=<optimized out>) at
./src/core/select.c:419
#25 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457
#26 0x00007f5a701d2e26 in osmo_select_main (polling=polling@entry=0) at
./src/core/select.c:496
#27 0x00005617aec925e6 in main (argc=1, argv=<optimized out>) at
./src/sgsn/sgsn_main.c:505
[1;34m[testenv][generic] Stopping podman container[0m
[0;94m[testenv][generic] + ['podman', 'kill',
'testenv-sgsn-generic-osmocom-nightly-20250717-1458-4f46c5d1-0'][0m
testenv-sgsn-generic-osmocom-nightly-20250717-1458-4f46c5d1-0
[1;34m[testenv][generic] Stopping testsuite (2319979)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][generic] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/504/artifa… [0m
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 10.82, 12.52, 8.87[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
4
days inactive
6
days old
jenkins-notifications@lists.osmocom.org
2 comments
1 participants
participants (1)
-
jenkins＠lists.osmocom.org