Build failed in Jenkins: ttcn3-sgsn-test #2782

Wed, 16 Jul 2025 03:01:48 -0700 

See 
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2782/display/redirect?page=changes>

Changes:

[Pau Espin Pedrol] hnbgw: Use static sigtran config also in 
osmo-hnbgw-with-pfcp.cfg

[Oliver Smith] smlc: fix running against latest

[lynxis] SGSN: Iu: use correct service request type


------------------------------------------
[...truncated 3.30 MiB...]
20250716100124033 DLM3UA 
NOTICE 0: ASP(asp-clnt-OsmoSGSN-A) Rx DAVA() for 0.24.3/0 
(xua_snm.c:478)
20250716100124033 DLSS7 
NOTICE 0: 
RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=as-clnt-OsmoSGSN-A,st=unavailable)
 changed to status 'available' (ss7_route.c:409)
20250716100124191 DLGSUP 
NOTICE GSUP connecting to 127.0.0.103:4222 
(gsup_client.c:77)
20250716100124191 DLINP 
NOTICE 127.0.0.103:4222 connection done (ipa.c:144)
20250716100124191 DLGSUP 
INFO GSUP link to 127.0.0.103:4222 UP 
(gsup_client.c:154)
20250716100124191 DLGSUP 
DEBUG GSUP sending PING (gsup_client.c:291)
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: Established a new IPA connection 
(conn_id=2)
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET 
(4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, 
tag := IPAC_IDTAG_UNITID (8) } } } }
20250716100124195 DLINP 
NOTICE received ID_GET for unit ID 0/0/0 (ipa.c:617)
20250716100124196 DLGSUP 
DEBUG GSUP receiving PONG (gsup_client.c:228)
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), 
u := omit }
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), 
u := omit }
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: 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)@9aca5ff8537b: 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)@9aca5ff8537b: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK 
(6), u := omit }
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK 
(6), u := omit }
20250716100124457 DGPRS DEBUG Checking for inactive 
LLMEs, time = 15890990 (sgsn.c:132)
20250716100124457 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: Tx HEARTBEAT (0 
unacked) (xua_asp_fsm.c:419)
20250716100124457 DLSS7 
DEBUG 0: ASP(sgsn0-0) Tx 8 bytes: [L2]> 01 00 03 03 00 00 
00 08  (ss7_asp.c:1383)
20250716100124458 DLSS7 
DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100124458 DLM3UA 
DEBUG 0: ASP(sgsn0-0) Received M3UA Message (ASPSM:BEAT) 
(m3ua.c:797)
20250716100124458 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event 
ASPSM_BEAT (m3ua.c:758)
20250716100124458 DLSS7 
DEBUG 0: ASP(sgsn0-0) Tx 8 bytes: [L2]> 01 00 03 06 00 00 
00 08  (ss7_asp.c:1383)
20250716100124458 DLSS7 
DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100124458 DLM3UA 
DEBUG 0: ASP(sgsn0-0) Received M3UA Message 
(ASPSM:BEAT-ACK) (m3ua.c:797)
20250716100124458 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event 
ASPSM_BEAT_ACK (m3ua.c:758)
20250716100124458 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: Rx HEARTBEAT ACK 
(xua_asp_fsm.c:799)
20250716100124838 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100124838 DLM3UA 
DEBUG 0: ASP(virt-rnc0-0) Received M3UA Message 
(XFER:DATA) (m3ua.c:797)
20250716100124838 DLM3UA 
DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)
20250716100124838 DLM3UA 
DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer(): M3UA data 
header: opc=195=0.24.3 dpc=188=0.23.4 sls=0 (m3ua.c:642)
20250716100124838 DLSS7 
DEBUG 0: m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not 
local, message is for routing (ss7_hmrt.c:248)
20250716100124839 DLSS7 
INFO 0: 
CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT loookup: 
OPC=195=0.24.3,DPC=188=0.23.4,SLS=0 -> eSLS=0: picked Normal Route via 'sgsn0' 
round-robin style (available) (ss7_combined_linkset.c:297)
20250716100124839 DLSS7 
DEBUG 0: Found route for dpc=188=0.23.4: pc=188=0.23.4 
mask=0x3fff=7.255.7 prio=5 via AS sgsn0 proto=m3ua (ss7_hmrt.c:193)
20250716100124839 DLSS7 
DEBUG 0: rt->dest.as proto is M3UA for dpc=188=0.23.4 
(ss7_hmrt.c:203)
20250716100124839 DLSS7 
DEBUG XUA_AS(sgsn0){AS_ACTIVE}: Received Event 
AS-TRANSFER.req (m3ua.c:562)
20250716100124839 DLSS7 
DEBUG 0: ASP(sgsn0-0) Tx 68 bytes: [L2]> 01 00 01 01 00 
00 00 44 02 10 00 31 00 00 00 c3 00 00 00 bc 03 02 00 00 09 00 03 07 0b 04 43 
bc 00 8e 04 43 c3 00 8e 11 00 09 00 0d 00 00 02 00 04 40 01 40 00 03 00 01 80 
00 00 00 00 06 00 08 00 00 00 03  (ss7_asp.c:1383)
20250716100124839 DMM DEBUG Rx CL 
IM (Reset) (ranap_common_cn.c:436)
20250716100124839 DMM DEBUG 
Decoding message RANAP_ResetIEs (ranap_decoder.c:2011) 
(ranap_decoder.c:2011)
20250716100124840 DLSS7 
DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100124840 DLM3UA 
DEBUG 0: ASP(sgsn0-0) Received M3UA Message (XFER:DATA) 
(m3ua.c:797)
20250716100124840 DLM3UA 
DEBUG 0: ASP(sgsn0-0) m3ua_rx_xfer (m3ua.c:599)
20250716100124840 DLM3UA 
DEBUG 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)
20250716100124840 DLSS7 
DEBUG 0: m3ua_hmdc_rx_from_l2(): dpc=195=0.24.3 not 
local, message is for routing (ss7_hmrt.c:248)
20250716100124840 DLSS7 
INFO 0: 
CombinedLinkset(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5) RT loookup: 
OPC=188=0.23.4,DPC=195=0.24.3,SLS=2 -> eSLS=2: picked Normal Route via 
'virt-rnc0' round-robin style (available) (ss7_combined_linkset.c:297)
20250716100124840 DLSS7 
DEBUG 0: Found route for dpc=195=0.24.3: pc=195=0.24.3 
mask=0x3fff=7.255.7 prio=5 via AS virt-rnc0 proto=m3ua (ss7_hmrt.c:193)
20250716100124840 DLSS7 
DEBUG 0: rt->dest.as proto is M3UA for dpc=195=0.24.3 
(ss7_hmrt.c:203)
20250716100124840 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event 
AS-TRANSFER.req (m3ua.c:562)
20250716100124840 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) Tx 60 bytes: [L2]> 01 00 01 01 
00 00 00 3c 02 10 00 2c 00 00 00 bc 00 00 00 c3 03 00 00 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)
SGSN_Test_0-RAN(1424)@9aca5ff8537b: RANAP: Received RESET-ACK in response to 
RESET, we're ready to go!
TC_iu_attach-BVCI196(1410)@9aca5ff8537b: Adding Client=TC_iu_attach(1430), 
IMSI='262420000001001'H, TLLI='ECE7DDD4'O, index=0
SGSN_Test-GSUP(1428)@9aca5ff8537b: Created GsupExpect[0] for "262420000001001" 
to be handled at TC_iu_attach(1430)
TC_iu_attach(1430)@9aca5ff8537b: 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 
:= '1B70'O, rac := '19'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)@9aca5ff8537b: Added conn table entry 
0TC_iu_attach(1430)5001176
SGSN_Test_0-SCCP(1422)@9aca5ff8537b: First idle individual index:0
SGSN_Test_0-RAN(1424)@9aca5ff8537b: patch: N(SD) for ConnIdx 0 set to 1
20250716100125287 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100125287 DLM3UA 
DEBUG 0: ASP(virt-rnc0-0) Received M3UA Message 
(XFER:DATA) (m3ua.c:797)
20250716100125287 DLM3UA 
DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer (m3ua.c:599)
20250716100125287 DLM3UA 
DEBUG 0: ASP(virt-rnc0-0) m3ua_rx_xfer(): M3UA data 
header: opc=195=0.24.3 dpc=188=0.23.4 sls=0 (m3ua.c:642)
20250716100125287 DLSS7 
DEBUG 0: m3ua_hmdc_rx_from_l2(): dpc=188=0.23.4 not 
local, message is for routing (ss7_hmrt.c:248)
20250716100125287 DLSS7 
DEBUG 0: 
CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT lookup: 
OPC=195=0.24.3,DPC=188=0.23.4,SLS=0 -> eSLS=0: use Normal Route via 'sgsn0' 
(ss7_combined_linkset.c:275)
20250716100125287 DLSS7 
DEBUG 0: Found route for dpc=188=0.23.4: pc=188=0.23.4 
mask=0x3fff=7.255.7 prio=5 via AS sgsn0 proto=m3ua (ss7_hmrt.c:193)
20250716100125287 DLSS7 
DEBUG 0: rt->dest.as proto is M3UA for dpc=188=0.23.4 
(ss7_hmrt.c:203)
20250716100125287 DLSS7 
DEBUG XUA_AS(sgsn0){AS_ACTIVE}: Received Event 
AS-TRANSFER.req (m3ua.c:562)
20250716100125287 DLSS7 
DEBUG 0: ASP(sgsn0-0) Tx 144 bytes: [L2]> 01 00 01 01 00 
00 00 90 02 10 00 7d 00 00 00 c3 00 00 00 bc 03 02 00 00 01 4c 4f d8 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 1b 70 
19 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)
20250716100125287 DMM DEBUG Rx CO 
IM (Initial UE Message) (ranap_common_cn.c:41)
20250716100125287 DMM DEBUG 
Decoding message RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) 
(ranap_decoder.c:3635)
20250716100125287 DRANAP NOTICE 
handle_co_initial(dir=1, proc=19) (iu_client.c:660)
20250716100125287 DRANAP NOTICE New RNC 262-42-2342 at 
RI=2,PC=195,SSN=142 (iu_client.c:244)
20250716100125287 DRANAP NOTICE RNC 262-42-2342: new 
LAC/RAC 262-42-4660-0 (iu_client.c:356)
20250716100125683 DLINP 
NOTICE sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 
(stream.c:348)
20250716100125683 DLSS7 
DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076)
20250716100125683 DLSS7 
INFO 0: ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32773 
flags=0x0 (ss7_asp.c:1000)
20250716100125683 DLSS7 
NOTICE 0: ASP(sgsn0-0) xUA SRV SHUTDOWN_EVENT 
(ss7_asp.c:1023)
20250716100125683 DLINP 
NOTICE sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 
SHUTDOWN_COMP (stream.c:348)
20250716100125683 DLSS7 
DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076)
20250716100125683 DLSS7 
INFO 0: ASP(sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 
flags=0x0 (ss7_asp.c:1000)
20250716100125683 DLSS7 
NOTICE 0: ASP(sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: 
SHUTDOWN_COMP (ss7_asp.c:1008)
20250716100125683 DLINP 
NOTICE 
SRVCONN(sgsn0-0,r=127.0.0.10:1905<->l=127.0.0.200:2905) connection closed with 
client (stream_srv.c:821)
20250716100125683 DLSS7 
DEBUG 0: ASP(sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100125683 DLSS7 
INFO sgsn0-0: connection closed (ss7_asp.c:1334)
20250716100125683 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: Received Event 
SCTP-COMM_DOWN.ind (ss7_asp.c:1340)
20250716100125683 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN 
(xua_asp_fsm.c:790)
20250716100125683 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_DOWN}: T(beat) stopped 
(xua_asp_fsm.c:401)
20250716100125683 DLSS7 
DEBUG XUA_AS(sgsn0){AS_ACTIVE}: Received Event 
ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377)
20250716100125683 DLSS7 
DEBUG XUA_AS(sgsn0){AS_ACTIVE}: state_chg to AS_PENDING 
(xua_as_fsm.c:598)
20250716100125683 DLSS7 
DEBUG XUA_AS(sgsn0){AS_PENDING}: Broadcasting NOTIFY Type 
State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)
20250716100125683 DLSS7 
NOTICE 0: 
RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=sgsn0,st=available) changed to 
status 'unavailable' (ss7_route.c:409)
20250716100125683 DLSS7 
NOTICE 0: 
RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=sgsn0,st=available) changed to 
status 'unavailable' (ss7_route.c:409)
20250716100125683 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) Tx 24 bytes: [L2]> 01 00 02 01 
00 00 00 18 00 06 00 08 00 00 00 02 00 12 00 08 00 00 00 bc  
(ss7_asp.c:1383)
20250716100125683 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, 
dropping M-ASP_DOWN.indication (xua_asp_fsm.c:122)
20250716100125683 DLSS7 
DEBUG XUA_ASP(sgsn0-0){ASP_DOWN}: No Layer Manager, 
dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:122)
Segmentation fault (core dumped)
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: IPA: Closed
SGSN_Test-GSUP(1428)@9aca5ff8537b: setverdict(fail): none -> fail reason: "GSUP 
Connection Lost", new component reason: "GSUP Connection Lost"
MC@9aca5ff8537b: Test Component 1428 has requested to stop MTC. Terminating 
current testcase execution.
SGSN_Test-GSUP(1428)@9aca5ff8537b: Final verdict of PTC: fail reason: "GSUP 
Connection Lost"
SGSN_Test-GSUP-IPA(1427)@9aca5ff8537b: Final verdict of PTC: none
-NSVCI98(1417)@9aca5ff8537b: Final verdict of PTC: none
-NSVCI97(1413)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-Gb1-NS1(1412)@9aca5ff8537b: Final verdict of PTC: none
TC_iu_attach-BVCI196(1410)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(1409)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-Gb2-NS2(1418)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test_0-RAN(1424)@9aca5ff8537b: Final verdict of PTC: none
-NSVCI99(1423)@9aca5ff8537b: Final verdict of PTC: none
TC_iu_attach-BVCI220(1420)@9aca5ff8537b: Final verdict of PTC: none
TC_iu_attach-BVCI210(1416)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test_0-SCCP(1422)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(1429)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-RNC_GTP(1426)@9aca5ff8537b: Final verdict of PTC: none
20250716100125686 DLINP 
NOTICE virt-rnc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 
(stream.c:348)
20250716100125686 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076)
20250716100125686 DLSS7 
INFO 0: ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32773 
flags=0x0 (ss7_asp.c:1000)
20250716100125686 DLSS7 
NOTICE 0: ASP(virt-rnc0-0) xUA SRV SHUTDOWN_EVENT 
(ss7_asp.c:1023)
20250716100125686 DLINP 
NOTICE virt-rnc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 
SHUTDOWN_COMP (stream.c:348)
20250716100125686 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1076)
20250716100125686 DLSS7 
INFO 0: ASP(virt-rnc0-0) xUA SRV SCTP NOTIFICATION 32769 
flags=0x0 (ss7_asp.c:1000)
20250716100125686 DLSS7 
NOTICE 0: ASP(virt-rnc0-0) xUA SRV SCTP_ASSOC_CHANGE: 
SHUTDOWN_COMP (ss7_asp.c:1008)
20250716100125686 DLINP 
NOTICE 
SRVCONN(virt-rnc0-0,r=127.0.0.104:23908<->l=127.0.0.200:2905) connection closed 
with client (stream_srv.c:821)
20250716100125686 DLSS7 
DEBUG 0: ASP(virt-rnc0-0) ss7_asp_xua_srv_conn_rx_cb(): 
sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1076)
20250716100125686 DLSS7 
INFO virt-rnc0-0: connection closed (ss7_asp.c:1334)
20250716100125686 DLSS7 
DEBUG XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: Received Event 
SCTP-COMM_DOWN.ind (ss7_asp.c:1340)
20250716100125686 DLSS7 
DEBUG XUA_ASP(virt-rnc0-0){ASP_ACTIVE}: state_chg to 
ASP_DOWN (xua_asp_fsm.c:790)
20250716100125686 DLSS7 
DEBUG XUA_ASP(virt-rnc0-0){ASP_DOWN}: T(beat) stopped 
(xua_asp_fsm.c:401)
20250716100125686 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: Received Event 
ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:377)
20250716100125686 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_ACTIVE}: state_chg to 
AS_PENDING (xua_as_fsm.c:598)
20250716100125686 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_PENDING}: Broadcasting NOTIFY 
Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)
20250716100125686 DLSS7 
NOTICE 0: 
RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=virt-rnc0,st=available) 
changed to status 'unavailable' (ss7_route.c:409)
20250716100125686 DLSS7 
NOTICE 0: 
RT(dpc=195=0.24.3,mask=0x3fff=7.255.7,prio=5,via=virt-rnc0,st=available) 
changed to status 'unavailable' (ss7_route.c:409)
20250716100125686 DLSS7 
DEBUG XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, 
dropping M-ASP_DOWN.indication (xua_asp_fsm.c:122)
20250716100125686 DLSS7 
DEBUG XUA_ASP(virt-rnc0-0){ASP_DOWN}: No Layer Manager, 
dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:122)
SGSN_Test-Gb2-BSSGP2(1419)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test_0-M3UA(1425)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(1414)@9aca5ff8537b: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(1408)@9aca5ff8537b: Final verdict of PTC: none
-NSVCI99-provIP(1421)@9aca5ff8537b: Final verdict of PTC: none
-NSVCI97-provIP(1411)@9aca5ff8537b: Final verdict of PTC: none
-NSVCI98-provIP(1415)@9aca5ff8537b: Final verdict of PTC: none
TC_iu_attach(1430)@9aca5ff8537b: Final verdict of PTC: none
MTC@9aca5ff8537b: Setting final verdict of the test case.
MTC@9aca5ff8537b: Local verdict of MTC: none
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-Gb0-NS0(1408): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(1409): none (none 
-> none)
MTC@9aca5ff8537b: Local verdict of PTC TC_iu_attach-BVCI196(1410): none (none 
-> none)
MTC@9aca5ff8537b: Local verdict of PTC -NSVCI97-provIP(1411): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-Gb1-NS1(1412): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC -NSVCI97(1413): none (none -> none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(1414): none (none 
-> none)
MTC@9aca5ff8537b: Local verdict of PTC -NSVCI98-provIP(1415): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC TC_iu_attach-BVCI210(1416): none (none 
-> none)
MTC@9aca5ff8537b: Local verdict of PTC -NSVCI98(1417): none (none -> none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-Gb2-NS2(1418): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(1419): none (none 
-> none)
MTC@9aca5ff8537b: Local verdict of PTC TC_iu_attach-BVCI220(1420): none (none 
-> none)
MTC@9aca5ff8537b: Local verdict of PTC -NSVCI99-provIP(1421): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test_0-SCCP(1422): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC -NSVCI99(1423): none (none -> none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test_0-RAN(1424): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test_0-M3UA(1425): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-RNC_GTP(1426): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-GSUP-IPA(1427): none (none -> 
none)
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-GSUP(1428): fail (none -> 
fail) reason: "GSUP Connection Lost"
MTC@9aca5ff8537b: Local verdict of PTC SGSN_Test-GGSN_GTP(1429): none (fail -> 
fail)
MTC@9aca5ff8537b: Local verdict of PTC TC_iu_attach(1430): none (fail -> fail)
MTC@9aca5ff8537b: Test case TC_iu_attach finished. Verdict: fail reason: GSUP 
Connection Lost
MTC@9aca5ff8537b: Starting external command 
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/ttcn3-tcpdump-stop.sh>
 SGSN_Tests_Iu.TC_iu_attach fail'.
Wed Jul 16 10:01:25 UTC 2025
------ SGSN_Tests_Iu.TC_iu_attach fail ------

Saving talloc report from 127.0.0.10:4245 to SGSN_Tests_Iu.TC_iu_attach.talloc
[testenv][generic] Looking for a coredump on lxc host
[testenv][generic] + ['ip', '-j', '-o', '-4', 'addr', 'show', 'dev', 
'eth0']
[testenv][generic] Coredump found and copied to log dir
[testenv][generic] sgsn unexpected exit during 
SGSN_Tests_Iu.TC_iu_attach! rc=0
[testenv][generic] Waiting until test has stopped...
20250716100127684 DLSS7 
DEBUG XUA_AS(sgsn0){AS_PENDING}: Received Event 
AS-T_REC_EXPD.ind (xua_as_fsm.c:349)
20250716100127684 DLSS7 
DEBUG XUA_AS(sgsn0){AS_PENDING}: T(r) expired; dropping 
queued messages (xua_as_fsm.c:657)
20250716100127684 DLSS7 
DEBUG XUA_AS(sgsn0){AS_PENDING}: state_chg to AS_DOWN 
(xua_as_fsm.c:667)
20250716100127687 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_PENDING}: Received Event 
AS-T_REC_EXPD.ind (xua_as_fsm.c:349)
20250716100127687 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_PENDING}: T(r) expired; 
dropping queued messages (xua_as_fsm.c:657)
20250716100127687 DLSS7 
DEBUG XUA_AS(virt-rnc0){AS_PENDING}: state_chg to AS_DOWN 
(xua_as_fsm.c:667)
Traceback (most recent call last):
  File 
"/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 
420, in main_run_commands
    interact.connect()
  File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/vty.py", 
line 84, in connect
    super().connect()
  File 
"/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 
88, in connect
    self.socket.connect((self.host, int(self.port)))
ConnectionRefusedError: [Errno 111] Connection refused
Waiting for packet dumper to finish... 0 (prev_count=-1, count=128096)
Waiting for packet dumper to finish... 1 (prev_count=128096, count=129664)
MTC@9aca5ff8537b: External command 
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/ttcn3-tcpdump-stop.sh>
 SGSN_Tests_Iu.TC_iu_attach fail' was executed successfully (exit status: 0).
MTC@9aca5ff8537b: Starting external command 
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/ttcn3-tcpdump-start.sh>
 SGSN_Tests_Iu.TC_iu_attach_encr'.
------ SGSN_Tests_Iu.TC_iu_attach_encr ------
Wed Jul 16 10:01:30 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w 
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2782/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap";>
 
><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2782/artifact/logs/testsuite/SGSN_Tests_Iu.TC_iu_attach_encr.pcap.stdout>
 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[testenv][generic] Running gdb to get a backtrace
[testenv][generic] + ['podman', 'exec', '-e', 
'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 
'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/_cache',>
 '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 
'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 
'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/ws/sgsn>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin',
 '-e', 'HOME=/home/osmocom-build', '-t', 
'testenv-sgsn-generic-osmocom-nightly-20250716-0948-0f935808-0', 'sh', '-c', 
'gdb --batch /usr/bin/osmo-sgsn 
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2782/artifact/logs/core>
 -ex bt | tee 
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2782/artifact/logs/core.backtrace']>
[New LWP 9253]
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  0x00007f4a71507ef7 in osmo_rai_name_buf (buf=0x7f4a704dd0b3 
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at 
./src/gsm/gsm48.c:196
#0  0x00007f4a71507ef7 in osmo_rai_name_buf (buf=0x7f4a704dd0b3 
"262-42-13200-0", buf_len=buf_len@entry=32, rai=rai@entry=0x20) at 
./src/gsm/gsm48.c:196
#1  0x00007f4a71507f85 in osmo_rai_name (rai=rai@entry=0x20) at 
./src/gsm/gsm48.c:209
#2  0x0000564304e0cba1 in sgsn_ranap_iu_event (ctx=0x0, 
type=RANAP_IU_EVENT_NEW_AREA, data=0x7ffed3e5b500) at 
./src/sgsn/gprs_ranap.c:147
#3  0x00007f4a71302ef4 in global_iu_event_new_area (rai=0x7ffed3e5b4f6, 
rnc_id=0x7ffed3e5b4e4) at ./src/iu_client.c:153
#4  iu_rnc_register (addr=0x7ffed3e5b4f6, rai=0x7ffed3e5b4f6, 
rnc_id=0x7ffed3e5b4e4) at ./src/iu_client.c:360
#5  ranap_handle_co_initial_ue (ies=0x56433b2e0d98, ctx=0x7ffed3e5b4f6) at 
./src/iu_client.c:500
#6  cn_ranap_handle_co_initial (ctx=ctx@entry=0x7ffed3e5ba60, 
message=message@entry=0x7ffed3e5b670) at ./src/iu_client.c:670
#7  0x00007f4a71300a45 in ranap_cn_rx_co (cb=cb@entry=0x7f4a71302a80 
<cn_ranap_handle_co_initial>, priv=priv@entry=0x7ffed3e5ba60, data=<optimized 
out>, len=<optimized out>) at ./src/ranap_common_cn.c:422
#8  0x00007f4a71303cfe in sccp_sap_up (oph=0x56433b378db8, _scu=0x56433b363410) 
at ./src/iu_client.c:1158
#9  0x00007f4a714ad1b0 in _osmo_fsm_inst_dispatch (fi=0x56433b368fc0, event=5, 
data=0x56433b36e5b0, file=0x7f4a7142c648 "sccp_scoc.c", line=820) at 
./src/core/fsm.c:875
#10 0x00007f4a71401b31 in scrc_node_6 (inst=inst@entry=0x56433b221880, 
xua=xua@entry=0x56433b36e5b0, called=called@entry=0x7ffed3e5bb80) at 
./src/sccp_scrc.c:399
#11 0x00007f4a714026d3 in scrc_rx_mtp_xfer_ind_xua 
(inst=inst@entry=0x56433b221880, xua=xua@entry=0x56433b36e5b0) at 
./src/sccp_scrc.c:570
#12 0x00007f4a713fd4f5 in mtp_user_prim_cb (oph=0x56433b3784c8, 
ctx=0x56433b221880) at ./src/sccp_instance.c:279
#13 0x00007f4a71411ebb in deliver_to_mtp_user (xua=0x56433b36f410, 
osu=0x56433b3613a0) at ./src/ss7_hmrt.c:106
#14 hmdt_message_for_distribution (xua=0x56433b36f410, inst=0x56433b361a00) at 
./src/ss7_hmrt.c:151
#15 m3ua_hmdc_rx_from_l2 (inst=0x56433b361a00, xua=xua@entry=0x56433b36f410) at 
./src/ss7_hmrt.c:246
#16 0x00007f4a713f6277 in m3ua_rx_xfer (xua=0x56433b36f410, asp=0x56433b361cf0) 
at ./src/m3ua.c:657
#17 m3ua_rx_msg (asp=asp@entry=0x56433b361cf0, msg=msg@entry=0x56433b377750) at 
./src/m3ua.c:817
#18 0x00007f4a7140ccd3 in xua_cli_read_cb (conn=<optimized out>, res=144, 
msg=0x56433b377750) at ./src/ss7_asp.c:1321
#19 0x00007f4a70ceaae2 in ?? () from /lib/x86_64-linux-gnu/libosmonetif.so.11
#20 0x00007f4a714ba950 in iofd_poll_ofd_cb_recvmsg_sendmsg (ofd=0x56433b2e09e0, 
what=1) at ./src/core/osmo_io_poll.c:84
#21 0x00007f4a714bac1e in iofd_poll_ofd_cb_dispatch (ofd=<optimized out>, 
what=<optimized out>) at ./src/core/osmo_io_poll.c:136
#22 0x00007f4a714bcd54 in poll_disp_fds (n_fd=<optimized out>) at 
./src/core/select.c:419
#23 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457
#24 0x00007f4a714bce26 in osmo_select_main (polling=polling@entry=0) at 
./src/core/select.c:496
#25 0x0000564304dde5e6 in main (argc=1, argv=<optimized out>) at 
./src/sgsn/sgsn_main.c:505
[testenv][generic] Stopping podman container
[testenv][generic] + ['podman', 'kill', 
'testenv-sgsn-generic-osmocom-nightly-20250716-0948-0f935808-0']
testenv-sgsn-generic-osmocom-nightly-20250716-0948-0f935808-0
[testenv][generic] Stopping testsuite (3450983)
[testenv] feed_watchdog_loop: podman container has stopped
[testenv][generic] Logs saved to: 
https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test/2782/artifact/logs/ 
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
load average: 3.41, 1.86, 1.32
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.

Reply via email to