December 2025 - jenkins-notifications

Build failed in Jenkins: ttcn3-gbproxy-test-io_uring #653
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-gbproxy-test-io_uring/653/dis…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 2.92 MiB...] MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558391 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558391 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558391 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558394 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558394 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558394 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558394 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=6, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101001'B, total_bits:='01000010000110000000000000101001'B == '42180029'O [38;5;57m20251222142558397 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558397 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558397 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558399 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558399 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558399 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558399 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558401 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558401 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558401 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558402 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558402 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558402 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558402 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=7, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101010'B, total_bits:='01000010000110000000000000101010'B == '4218002A'O [38;5;57m20251222142558404 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558404 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558404 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558405 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558405 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558405 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558405 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558406 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558406 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558406 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558408 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558408 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558408 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558408 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=8, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101011'B, total_bits:='01000010000110000000000000101011'B == '4218002B'O [38;5;57m20251222142558410 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558410 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558410 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558411 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558411 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558411 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558411 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558414 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558414 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558414 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558416 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558416 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558417 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558417 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=9, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101100'B, total_bits:='01000010000110000000000000101100'B == '4218002C'O [38;5;57m20251222142558421 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558421 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558421 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558425 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558425 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558425 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558425 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558431 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558431 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558431 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558436 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558436 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558436 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558436 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=0, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100011'B, total_bits:='01000010001000000000000000100011'B == '42200023'O [38;5;57m20251222142558443 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558443 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558443 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558456 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558456 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558456 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558456 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558462 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558462 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558462 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558470 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558470 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558470 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558470 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=1, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100100'B, total_bits:='01000010001000000000000000100100'B == '42200024'O [38;5;57m20251222142558477 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558477 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558477 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558484 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558484 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558484 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558484 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558490 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558490 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558490 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558496 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558496 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558496 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558496 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=2, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100101'B, total_bits:='01000010001000000000000000100101'B == '42200025'O [38;5;57m20251222142558503 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558503 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558503 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558510 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558510 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558510 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558510 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558517 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558518 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558518 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558524 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558524 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558524 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558524 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=3, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100110'B, total_bits:='01000010001000000000000000100110'B == '42200026'O [38;5;57m20251222142558533 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558533 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558533 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558539 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558539 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558539 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558539 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558546 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558546 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558546 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558552 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558552 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558552 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558552 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=4, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100111'B, total_bits:='01000010001000000000000000100111'B == '42200027'O [38;5;57m20251222142558560 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558560 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558560 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558566 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558566 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425) 20251222142558566 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618) 20251222142558566 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558573 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558573 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558573 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed [38;5;57m20251222142558579 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558579 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425) 20251222142558579 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618) 20251222142558579 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed MTC@114173dc6707: f_gen_tmsi(suffix:=5, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101000'B, total_bits:='01000010001000000000000000101000'B == '42200028'O [38;5;57m20251222142558585 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20251222142558585 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053) 20251222142558585 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204) MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed FATAL: command execution failed java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins6032169796691918818.sh java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-gbproxy-test-io_uring #653

1 0

Build failed in Jenkins: ttcn3-bsc-test-sccplite-latest #2536
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2536…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 95.63 MiB...] MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config)# " id 8 MTC@e0eada7afaad: Message with id 8 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 0" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config-msc)# " id 9 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config-msc)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config-msc)# " id 9 MTC@e0eada7afaad: Message with id 9 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "osmux off" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config-msc)# " id 10 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config-msc)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config-msc)# " id 10 MTC@e0eada7afaad: Message with id 10 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "exit" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config)# " id 11 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config)# " id 11 MTC@e0eada7afaad: Message with id 11 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "exit" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 12 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 12 MTC@e0eada7afaad: Message with id 12 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Creating new PTC with component type StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS. HC@e0eada7afaad: PTC VirtMSC-STATS(1164) finished. Process statistics: { process id: 19177, terminated normally, exit status: 0, user time: 0.038806 s, system time: 0.017247 s, maximum resident set size: 102808, integral resident set size: 0, page faults not requiring physical I/O: 4615, page faults requiring physical I/O: 3, swaps: 0, block input operations: 0, block output operations: 32, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 81, involuntary context switches: 5 } HC@e0eada7afaad: PTC VirtMSC-SCCP(1165) finished. Process statistics: { process id: 19178, terminated normally, exit status: 0, user time: 0.041452 s, system time: 0.018423 s, maximum resident set size: 102792, integral resident set size: 0, page faults not requiring physical I/O: 4612, page faults requiring physical I/O: 2, swaps: 0, block input operations: 0, block output operations: 96, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 235, involuntary context switches: 18 } HC@e0eada7afaad: PTC VirtMSC-IPA(1166) finished. Process statistics: { process id: 19179, terminated normally, exit status: 0, user time: 0.050993 s, system time: 0.027458 s, maximum resident set size: 103044, integral resident set size: 0, page faults not requiring physical I/O: 4585, page faults requiring physical I/O: 5, swaps: 0, block input operations: 0, block output operations: 88, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 239, involuntary context switches: 8 } HC@e0eada7afaad: PTC VirtMSC-IPA-WAIT(1167) finished. Process statistics: { process id: 19180, terminated normally, exit status: 0, user time: 0.039210 s, system time: 0.007129 s, maximum resident set size: 100492, integral resident set size: 0, page faults not requiring physical I/O: 4506, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 16, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 39, involuntary context switches: 9 } HC@e0eada7afaad: PTC VirtMSC-RAN(1168) finished. Process statistics: { process id: 19182, terminated normally, exit status: 0, user time: 0.053956 s, system time: 0.020233 s, maximum resident set size: 106964, integral resident set size: 0, page faults not requiring physical I/O: 4682, page faults requiring physical I/O: 2, swaps: 0, block input operations: 0, block output operations: 120, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 196, involuntary context switches: 15 } HC@e0eada7afaad: PTC IPA-CTRL-CLI-IPA(1169) finished. Process statistics: { process id: 19183, terminated normally, exit status: 0, user time: 0.100597 s, system time: 0.048111 s, maximum resident set size: 101696, integral resident set size: 0, page faults not requiring physical I/O: 4597, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 2240, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 5321, involuntary context switches: 52 } HC@e0eada7afaad: PTC VirtMGW-MGCP-0(1170) finished. Process statistics: { process id: 19184, terminated normally, exit status: 0, user time: 0.043743 s, system time: 0.035790 s, maximum resident set size: 102948, integral resident set size: 0, page faults not requiring physical I/O: 4595, page faults requiring physical I/O: 7, swaps: 0, block input operations: 0, block output operations: 104, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 152, involuntary context switches: 10 } HC@e0eada7afaad: PTC IPA-BTS0-TRX0-RSL-IPA(1171) finished. Process statistics: { process id: 19186, terminated normally, exit status: 0, user time: 0.063228 s, system time: 0.012645 s, maximum resident set size: 102592, integral resident set size: 0, page faults not requiring physical I/O: 4587, page faults requiring physical I/O: 4, swaps: 0, block input operations: 0, block output operations: 168, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 342, involuntary context switches: 9 } HC@e0eada7afaad: PTC IPA-BTS0-TRX0-RSL-RSL(1172) finished. Process statistics: { process id: 19187, terminated normally, exit status: 0, user time: 0.055844 s, system time: 0.042957 s, maximum resident set size: 104032, integral resident set size: 0, page faults not requiring physical I/O: 4610, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 304, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 498, involuntary context switches: 15 } HC@e0eada7afaad: PTC IPA-BTS1-TRX0-RSL-IPA(1173) finished. Process statistics: { process id: 19189, terminated normally, exit status: 0, user time: 0.072395 s, system time: 0.025551 s, maximum resident set size: 102348, integral resident set size: 0, page faults not requiring physical I/O: 4582, page faults requiring physical I/O: 13, swaps: 0, block input operations: 0, block output operations: 128, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 264, involuntary context switches: 10 } HC@e0eada7afaad: PTC IPA-BTS1-TRX0-RSL-RSL(1174) finished. Process statistics: { process id: 19190, terminated normally, exit status: 0, user time: 0.042339 s, system time: 0.034641 s, maximum resident set size: 102968, integral resident set size: 0, page faults not requiring physical I/O: 4617, page faults requiring physical I/O: 1, swaps: 0, block input operations: 0, block output operations: 232, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 384, involuntary context switches: 10 } HC@e0eada7afaad: PTC TC_ho_int_a5_0(1175) finished. Process statistics: { process id: 19192, terminated normally, exit status: 0, user time: 0.108794 s, system time: 0.027198 s, maximum resident set size: 110188, integral resident set size: 0, page faults not requiring physical I/O: 4780, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 256, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 538, involuntary context switches: 14 } HC@e0eada7afaad: PTC was created. Component reference: 1176, component type: StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS, testcase name: TC_ho_int_a5_1, process id: 19255. VirtMSC-STATS(1176)@e0eada7afaad: Initializing variables, timers and ports of component type StatsD_Checker.StatsD_Checker_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1176, alive: no, type: StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS. MTC@e0eada7afaad: Starting function main("127.0.0.1", 8125, 5.000000) on component VirtMSC-STATS(1176). MTC@e0eada7afaad: Function was started. MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 0 bssmap reset" 20251222142558384 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) VTY requests BSSMAP RESET (bsc_vty.c:3616) MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 13 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 13 MTC@e0eada7afaad: Message with id 13 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 1 bssmap reset" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "% No such MSC: nr 1" id 14 MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 15 MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+$*$\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "*% Unknown command." unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "% No such MSC: nr 1" with ? matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "% No such MSC: nr 1" id 14 MTC@e0eada7afaad: Message with id 14 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 15 MTC@e0eada7afaad: Message with id 15 was extracted from the queue of BSCVTY. [38;5;45m20251222142558387 [1;31mDLSCCP[0;m[38;5;45m [1;31mERROR[0;m[38;5;45m 0: SCCP(rctx=0) MTP-TRANSFER.req from SCCP for OPC=187=0.23.3,DPC=185=0.23.1,SLS=2: no route! (sccp_scrc.c:177)[0;m MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 2 bssmap reset" [38;5;45m20251222142558387 [1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m 0: SCCP(rctx=0) SCRC Routing Failure (MTP failure) for CL message CL:CLDT (sccp_sclc.c:359)[0;m MTC@e0eada7afaad: Start timer T: 2 s 20251222142558387 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) (calling_addr=RI=2,PC=185,SSN=254) N-NOTICE.ind cause=5='MTP failure' importance=0 (osmo_bsc_sigtran.c:168) MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "% No such MSC: nr 2" id 16 MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 17 MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+$*$\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "*% Unknown command." unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "% No such MSC: nr 2" with ? matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "% No such MSC: nr 2" id 16 MTC@e0eada7afaad: Message with id 16 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 17 MTC@e0eada7afaad: Message with id 17 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Creating new alive PTC with component type SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP. VirtMSC-STATS(1176)@e0eada7afaad: Port STATSVTY was started. VirtMSC-STATS(1176)@e0eada7afaad: Port STATSD_PROC was started. VirtMSC-STATS(1176)@e0eada7afaad: Port STATS was started. VirtMSC-STATS(1176)@e0eada7afaad: Component type StatsD_Checker.StatsD_Checker_CT was initialized. VirtMSC-STATS(1176)@e0eada7afaad: Starting function main("127.0.0.1", 8125, 5.000000). VirtMSC-STATS(1176)@e0eada7afaad: Mapping port VirtMSC-STATS(1176):STATS to system:STATS. VirtMSC-STATS(1176)@e0eada7afaad: Port STATS was mapped to system:STATS. VirtMSC-STATS(1176)@e0eada7afaad: Map operation of VirtMSC-STATS(1176):STATS to system:STATS finished. VirtMSC-STATS(1176)@e0eada7afaad: entering f__IPL4__PROVIDER__listen: 127.0.0.1:8125 / UDP VirtMSC-STATS(1176)@e0eada7afaad: Mapping port VirtMSC-STATS(1176):STATSVTY to system:STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system integer : 2 id 1 VirtMSC-STATS(1176)@e0eada7afaad: Port STATSVTY was mapped to system:STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Map operation of VirtMSC-STATS(1176):STATSVTY to system:STATSVTY finished. HC@e0eada7afaad: PTC was created. Component reference: 1177, component type: SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP, testcase name: TC_ho_int_a5_1, process id: 19256. VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 1, prompt := "OsmoBSC> ", has_wildcards := false } } VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 2, prompt := "OsmoBSC# ", has_wildcards := false } } VirtMSC-SCCP(1177)@e0eada7afaad: Initializing variables, timers and ports of component type SCCP_Emulation.SCCP_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1177, alive: yes, type: SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP. MTC@e0eada7afaad: Creating new alive PTC with component type IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA. VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 3, prompt := "OsmoBSC(*)", has_wildcards := true } } VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system charstring : "enable" VirtMSC-STATS(1176)@e0eada7afaad: Start timer T: 2 s VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: 2 with ? matched VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): integer : 2 id 1 VirtMSC-STATS(1176)@e0eada7afaad: Message with id 1 was extracted from the queue of STATSVTY. VirtMSC-SCCP(1177)@e0eada7afaad: Port SCCP_SP_PORT was started. VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT was started. VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system charstring : "OsmoBSC# " id 2 VirtMSC-SCCP(1177)@e0eada7afaad: Component type SCCP_Emulation.SCCP_CT was initialized. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): charstring : "OsmoBSC# " id 2 VirtMSC-STATS(1176)@e0eada7afaad: Message with id 2 was extracted from the queue of STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Stop timer T: 2 s VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system charstring : "stats reset" VirtMSC-STATS(1176)@e0eada7afaad: Start timer T: 2 s VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system charstring : "OsmoBSC# " id 3 VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): charstring : "OsmoBSC# " id 3 VirtMSC-STATS(1176)@e0eada7afaad: Message with id 3 was extracted from the queue of STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Stop timer T: 2 s HC@e0eada7afaad: PTC was created. Component reference: 1178, component type: IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA, testcase name: TC_ho_int_a5_1, process id: 19257. VirtMSC-IPA(1178)@e0eada7afaad: Initializing variables, timers and ports of component type IPA_Emulation.IPA_Emulation_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1178, alive: yes, type: IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA. MTC@e0eada7afaad: Mapping port VirtMSC-IPA(1178):IPA_PORT to system:IPA_CODEC_PT. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port CFG_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port MTP3_SP_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_MGCP_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_RSL_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_OML_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_CTRL_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Component type IPA_Emulation.IPA_Emulation_CT was initialized. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_PORT was mapped to system:IPA_CODEC_PT. MTC@e0eada7afaad: Map operation of VirtMSC-IPA(1178):IPA_PORT to system:IPA_CODEC_PT finished. MTC@e0eada7afaad: Connecting ports VirtMSC-IPA(1178):MTP3_SP_PORT and VirtMSC-SCCP(1177):MTP3_SCCP_PORT. VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT is waiting for connection from VirtMSC-IPA(1178):MTP3_SP_PORT on UNIX pathname /tmp/ttcn3-portconn-7511f0e4. VirtMSC-IPA(1178)@e0eada7afaad: Port MTP3_SP_PORT has established the connection with VirtMSC-SCCP(1177):MTP3_SCCP_PORT using transport type UNIX. VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT has accepted the connection from VirtMSC-IPA(1178):MTP3_SP_PORT. MTC@e0eada7afaad: Connect operation on VirtMSC-IPA(1178):MTP3_SP_PORT and VirtMSC-SCCP(1177):MTP3_SCCP_PORT finished. MTC@e0eada7afaad: Creating new alive PTC with component type IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT. HC@e0eada7afaad: PTC was created. Component reference: 1179, component type: IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT, testcase name: TC_ho_int_a5_1, process id: 19258. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Initializing variables, timers and ports of component type IPA_Emulation.IPA_EventWaiter_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1179, alive: yes, type: IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT. MTC@e0eada7afaad: Connecting ports VirtMSC-IPA(1178):IPA_SP_PORT and VirtMSC-IPA-WAIT(1179):IPA_SP_PORT. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT is waiting for connection from VirtMSC-IPA-WAIT(1179):IPA_SP_PORT on UNIX pathname /tmp/ttcn3-portconn-5202b695. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Port IPA_SP_PORT was started. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Component type IPA_Emulation.IPA_EventWaiter_CT was initialized. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Port IPA_SP_PORT has established the connection with VirtMSC-IPA(1178):IPA_SP_PORT using transport type UNIX. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT has accepted the connection from VirtMSC-IPA-WAIT(1179):IPA_SP_PORT. MTC@e0eada7afaad: Connect operation on VirtMSC-IPA(1178):IPA_SP_PORT and VirtMSC-IPA-WAIT(1179):IPA_SP_PORT finished. MTC@e0eada7afaad: Starting function waiter_main({ ev_type := ASP_IPA_EVENT_ID_ACK (3), conn_id := ?, id_resp := * }) on component VirtMSC-IPA-WAIT(1179). MTC@e0eada7afaad: Function was started. MTC@e0eada7afaad: Starting function main_server("127.0.0.1", 5000, true, IPA_INIT_SEND_IPA_ID_ACK (1), true) on component VirtMSC-IPA(1178). MTC@e0eada7afaad: Function was started. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Starting function waiter_main({ ev_type := ASP_IPA_EVENT_ID_ACK (3), conn_id := ?, id_resp := * }). VirtMSC-IPA(1178)@e0eada7afaad: Starting function main_server("127.0.0.1", 5000, true, IPA_INIT_SEND_IPA_ID_ACK (1), true). VirtMSC-IPA(1178)@e0eada7afaad: entering f__IPL4__PROVIDER__listen: 127.0.0.1:5000 / TCP FATAL: command execution failed java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: java.io.IOException: Backing channel 'build5-deb12build-ansible' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins2152644542038171758.sh java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-bsc-test-sccplite-latest #2536

1 0

Build failed in Jenkins: osmo-gsm-tester_build-osmo-trx #8434
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_build-osmo-trx/8434…> Changes: ------------------------------------------ Started by upstream project "master-osmo-trx" build number 8718 originally caused by: Started by upstream project "master-libusrp" build number 2951 originally caused by: Started by timer Running as SYSTEM Building remotely on build5-deb12build-ansible (obs ttcn3_with_linux_6.1_or_higher qemu registry-build-amd64 ttcn3 osmo-gsm-tester-build ttcn3-ggsn-test-kernel-git io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/osmo-gsm-tester_build-osmo-trx FATAL: java.io.IOException: Unexpected termination of the channel java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to build5-deb12build-ansible at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1923) at hudson.remoting.Request.call(Request.java:204) at hudson.remoting.Channel.call(Channel.java:1111) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.mkdirs(FilePath.java:1387) at hudson.model.AbstractProject.checkout(AbstractProject.java:1244) at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:649) at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:85) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:522) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Caused: hudson.remoting.RequestAbortedException at hudson.remoting.Request.abort(Request.java:358) at hudson.remoting.Channel.terminate(Channel.java:1196) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:95) ERROR: Step ‘Archive the artifacts’ failed: no workspace for osmo-gsm-tester_build-osmo-trx #8434

1 0

Jenkins build became unstable: ttcn3-hnbgw-test-io_uring #656
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-io_uring/656/displ…>

1 0

Build failed in Jenkins: ttcn3-hnbgw-test-latest #1288
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/1288/displa…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 30.43 MiB...] [38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc1-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 03 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-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 b9 (ss7_asp.c:1400)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m [38;5;23m20251222125458107 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(virt-msc1-0,r=::ffff:127.0.0.1:23907<->l=::ffff:127.0.0.1:2905) connection closed with client (stream_srv.c:822)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc1-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;43m20251222125458107 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m virt-msc1-0: connection closed (ss7_asp.c:1349)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc1){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc1){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc1){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=virt-msc1,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=virt-msc1,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 02 (ss7_asp.c:1400)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-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 02 (ss7_asp.c:1400)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m [38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m [38;5;23m20251222125458108 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m [38;5;43m20251222125458108 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m [38;5;43m20251222125458108 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251222125458108 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1021)[0;m [38;5;23m20251222125458108 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) error to send: Broken pipe (stream_srv.c:796)[0;m [38;5;23m20251222125458108 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m [38;5;43m20251222125458108 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m [38;5;43m20251222125458108 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251222125458108 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006)[0;m [38;5;23m20251222125458108 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) error to send: Broken pipe (stream_srv.c:796)[0;m [38;5;43m20251222125458108 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251222125458108 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251222125458109 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251222125458109 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=10=0.1.2 sls=1 (m3ua.c:632)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=10=0.1.2 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251222125458109 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=10=0.1.2,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=189=0.23.5,DPC=10=0.1.2,SLS=1 -> eSLS=17: Normal Route via 'virt-sgsn1' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251222125458109 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=10=0.1.2: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 10=0.1.2: Start (ss7_instance.c:879)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=189=0.23.5,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=10=0.1.2,DPC=189=0.23.5,SLS=1 -> eSLS=17: use Normal Route via 'hnbgw0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251222125458109 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 10=0.1.2. Tx TFP (DUNA) to concerned SP 189=0.23.5 (mtp3_rtpc.c:120)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(hnbgw0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 0a 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;23m20251222125458109 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) connection closed with client (stream_srv.c:822)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;43m20251222125458109 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m virt-sgsn0-0: connection closed (ss7_asp.c:1349)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-sgsn0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-sgsn0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251222125458109 [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=virt-sgsn0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458109 [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=virt-sgsn0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 bc (ss7_asp.c:1400)[0;m [38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m [38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m 20251222125458110 [1;33mDRANAP[0;m [1;33mNOTICE[0;m cnlink(msc-1)[0x556305a6aa50]{DISCONNECTED}: link lost (cnlink_fsm.c:73)[0;m [38;5;23m20251222125458110 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) message received (res=12) (stream_srv.c:812)[0;m [38;5;23m20251222125458110 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m r=127.0.0.1:9999<->l=127.0.0.1:29169 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m 20251222125458110 [1;34mDMAIN[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0) hnb_read_cb(): sctp_recvmsg() returned -11 (flags=0x80) (hnb.c:238)[0;m 20251222125458110 [1;33mDMAIN[0;m [1;33mNOTICE[0;m (127.0.0.1:9999 TTCN3-HNB-0) sctp_recvmsg(r=NULL<->l=127.0.0.1:29169) = SCTP_SHUTDOWN_EVENT, closing conn (hnb.c:269)[0;m 20251222125458110 [1;32mDMAIN[0;m [1;32mINFO[0;m (? TTCN3-HNB-0) connection closed (hnb.c:335)[0;m 20251222125458110 [1;32mDMAIN[0;m [1;32mINFO[0;m (disconnected TTCN3-HNB-0) Releasing HNB context (hnb.c:166)[0;m [1;36m20251222125458110 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{connected}: Received Event MAP_RUA_EV_HNB_LINK_LOST (context_map.c:185)[0;m [1;36m20251222125458110 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{connected}: State change to disrupted (X31, 5s) (context_map_rua.c:246)[0;m [33m20251222125458110 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{connected}: Received Event MAP_SCCP_EV_RAN_LINK_LOST (context_map_rua.c:267)[0;m [38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Received SCCP User Primitive (N-DISCONNECT.request) (sccp_sap.c:222)[0;m [38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(117)[0x556305a88ed0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_sap.c:263)[0;m [38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=007b8ca7), PART(T=Source Reference,L=4,D=00000075), PART(T=SCCP Cause,L=4,D=00000300) (sccp_scrc.c:452)[0;m [38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) dpc_accessible(DPC=188=0.23.4): true (sccp_scrc.c:85)[0;m [38;5;43m20251222125458110 [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=0=0.0.0,DPC=188=0.23.4,SLS=2 -> eSLS=50: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251222125458110 [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=189=0.23.5,DPC=188=0.23.4,SLS=2 -> eSLS=18: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251222125458110 [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 dyn via AS as-clnt-CN proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251222125458111 [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 (mtp3_hmrt.c:90)[0;m [38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-clnt-CN)[0x556305a5ff00]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 bd 00 00 00 bc 03 00 00 02 04 7b 8c a7 00 00 75 00 00 00 00 00 (ss7_asp.c:1400)[0;m [38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(117)[0x556305a88ed0]{ACTIVE}: State change to DISCONN_PEND (no timeout) (sccp_scoc_fsm.c:373)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{connected}: State change to disconnected (X31, 5s) (context_map_sccp.c:351)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{disrupted}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:203)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{disrupted}: Freeing instance (context_map.c:203)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{disrupted}: Deallocated (fsm.c:568)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{disconnected}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:207)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{disconnected}: Freeing instance (context_map.c:207)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{disconnected}: Deallocated (fsm.c:568)[0;m 20251222125458111 [1;32mDMAIN[0;m [1;32mINFO[0;m (disconnected TTCN3-HNB-0) RUA-4460376 SCCP-117 sgsn-0 MI=IMSI-262420000000003: Deallocating (context_map.c:226)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{connected}: Received Event MAP_RUA_EV_HNB_LINK_LOST (context_map.c:185)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{connected}: State change to disrupted (X31, 5s) (context_map_rua.c:246)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{connected}: Received Event MAP_SCCP_EV_RAN_LINK_LOST (context_map_rua.c:267)[0;m [38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Received SCCP User Primitive (N-DISCONNECT.request) (sccp_sap.c:222)[0;m [38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(119)[0x5563059717e0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_sap.c:263)[0;m [38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=003dad6b), PART(T=Source Reference,L=4,D=00000077), PART(T=SCCP Cause,L=4,D=00000300) (sccp_scrc.c:452)[0;m [38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) dpc_accessible(DPC=188=0.23.4): true (sccp_scrc.c:85)[0;m [38;5;43m20251222125458111 [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=0=0.0.0,DPC=188=0.23.4,SLS=0 -> eSLS=48: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251222125458111 [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=189=0.23.5,DPC=188=0.23.4,SLS=0 -> eSLS=16: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251222125458111 [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 dyn via AS as-clnt-CN proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251222125458111 [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 (mtp3_hmrt.c:90)[0;m [38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-clnt-CN)[0x556305a5ff00]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 bd 00 00 00 bc 03 00 00 00 04 3d ad 6b 00 00 77 00 00 00 00 00 (ss7_asp.c:1400)[0;m [38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(119)[0x5563059717e0]{ACTIVE}: State change to DISCONN_PEND (no timeout) (sccp_scoc_fsm.c:373)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{connected}: State change to disconnected (X31, 5s) (context_map_sccp.c:351)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{disrupted}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:203)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{disrupted}: Freeing instance (context_map.c:203)[0;m [1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{disrupted}: Deallocated (fsm.c:568)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{disconnected}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:207)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{disconnected}: Freeing instance (context_map.c:207)[0;m [33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{disconnected}: Deallocated (fsm.c:568)[0;m 20251222125458111 [1;32mDMAIN[0;m [1;32mINFO[0;m (disconnected TTCN3-HNB-0) RUA-15062496 SCCP-119 sgsn-0 MI=NONE: Deallocating (context_map.c:226)[0;m [38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251222125458112 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Received M3UA Message (SNM:DUNA) (m3ua.c:811)[0;m [38;5;49m20251222125458112 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.1.2/0 (xua_snm.c:501)[0;m [38;5;43m20251222125458112 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=10=0.1.2,mask=0x3fff=7.255.7,prio=5,via=as-clnt-CN,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ss7_user(SCCP) Delivering to MTP User: MTP-PAUSE.indication (mtp_sap.c:204)[0;m [38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m [38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:318)[0;m [38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m [33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m sccp_sap_up(N-PCSTATE.indication) (hnbgw_sccp.c:331)[0;m [33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m N-PCSTATE ind: affected_pc=10=0.1.2 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:237)[0;m [38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) xua_cli_read_cb(): sctp_recvmsg() returned 16 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251222125458112 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Received M3UA Message (SNM:DUNA) (m3ua.c:811)[0;m [38;5;49m20251222125458112 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Rx DUNA() for 0.23.4/0 (xua_snm.c:501)[0;m [38;5;43m20251222125458112 [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=as-clnt-CN,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ss7_user(SCCP) Delivering to MTP User: MTP-PAUSE.indication (mtp_sap.c:204)[0;m [38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m [38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:318)[0;m [38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m [33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m sccp_sap_up(N-PCSTATE.indication) (hnbgw_sccp.c:331)[0;m [33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m N-PCSTATE ind: affected_pc=188=0.23.4 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:237)[0;m [33m20251222125458112 [1;33mDCN[0;m[33m [1;33mNOTICE[0;m[33m (sgsn-0) now unreachable: N-PCSTATE ind: pc=188=0.23.4 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:303)[0;m 20251222125458112 [1;34mDRANAP[0;m [1;34mDEBUG[0;m cnlink(sgsn-0)[0x556305a6d450]{CONNECTED}: State change to DISCONNECTED (T4, 60s) (cnlink_fsm.c:203)[0;m 20251222125458112 [1;33mDRANAP[0;m [1;33mNOTICE[0;m cnlink(sgsn-0)[0x556305a6d450]{DISCONNECTED}: link lost (cnlink_fsm.c:73)[0;m 20251222125458112 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49132<->l=127.0.0.1:4261 (telnet_interface.c:138) TC_sgsnpool_L3Complete_no_nri_round_robin0(994)@50b239c1fc8c: Final verdict of PTC: pass [38;5;43m20251222125458115 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251222125458115 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251222125458115 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251222125458115 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 sls=2 (m3ua.c:632)[0;m [38;5;43m20251222125458115 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251222125458115 [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 Lookup: OPC=189=0.23.5,DPC=188=0.23.4,SLS=2 -> eSLS=18: Normal Route via 'virt-sgsn0' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251222125458115 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=188=0.23.4: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251222125458115 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 188=0.23.4: Start (ss7_instance.c:879)[0;m 20251222125458116 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49124<->l=127.0.0.1:4261 (telnet_interface.c:138) HNBGW-MGCP(992)@50b239c1fc8c: Final verdict of PTC: none [38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=189=0.23.5,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=188=0.23.4,DPC=189=0.23.5,SLS=2 -> eSLS=18: use Normal Route via 'hnbgw0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251222125458116 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 188=0.23.4. Tx TFP (DUNA) to concerned SP 189=0.23.5 (mtp3_rtpc.c:120)[0;m [38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(hnbgw0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251222125458118 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251222125458118 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Received M3UA Message (SNM:DUNA) (m3ua.c:811)[0;m [38;5;49m20251222125458118 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.23.4/0 (xua_snm.c:501)[0;m [38;5;43m20251222125458118 [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=as-clnt-CN,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251222125458118 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ss7_user(SCCP) Delivering to MTP User: MTP-PAUSE.indication (mtp_sap.c:204)[0;m [38;5;45m20251222125458118 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m [38;5;45m20251222125458118 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:318)[0;m [38;5;45m20251222125458118 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m [33m20251222125458118 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m sccp_sap_up(N-PCSTATE.indication) (hnbgw_sccp.c:331)[0;m [33m20251222125458118 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m N-PCSTATE ind: affected_pc=188=0.23.4 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:237)[0;m TC_sgsnpool_L3Complete_no_nri_round_robin0(993)@50b239c1fc8c: Final verdict of PTC: pass [38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 bc 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251222125458117 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251222125458117 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251222125458117 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 sls=0 (m3ua.c:632)[0;m [38;5;43m20251222125458117 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251222125458117 [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 Lookup: OPC=189=0.23.5,DPC=188=0.23.4,SLS=0 -> eSLS=16: Normal Route via 'virt-sgsn0' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251222125458117 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=188=0.23.4: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251222125458117 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Tx TFP (DUNA) inaccessible SP 188=0.23.4 to concerned SP 189=0.23.5: inhibit due to T8 (mtp3_rtpc.c:74)[0;m HNBGW_Test.msc0-M3UA(981)@50b239c1fc8c: Final verdict of PTC: none 20251222125458124 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49142<->l=127.0.0.1:4261 (telnet_interface.c:138) TC_sgsnpool_L3Complete_no_nri_round_robin0(995)@50b239c1fc8c: Final verdict of PTC: pass MTC@50b239c1fc8c: Setting final verdict of the test case. MTC@50b239c1fc8c: Local verdict of MTC: pass MTC@50b239c1fc8c: Local verdict of PTC MutexDispCT-TC_sgsnpool_L3Complete_no_nri_round_robin(972): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC VirtHNBGW-STATS(973): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC IPA-CTRL-CLI-IPA(974): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW-PFCP(975): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh0(976): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh0-RUA(977): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh1(978): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh1-RUA(979): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc0-SCCP(980): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc0-M3UA(981): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc0-RAN(982): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc1-SCCP(983): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc1-M3UA(984): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc1-RAN(985): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn0-SCCP(986): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn0-M3UA(987): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn0-RAN(988): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn1-SCCP(989): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn1-M3UA(990): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn1-RAN(991): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC HNBGW-MGCP(992): none (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin0(993): pass (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin0(994): pass (pass -> pass) MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin0(995): pass (pass -> pass) MTC@50b239c1fc8c: Test case TC_sgsnpool_L3Complete_no_nri_round_robin finished. Verdict: pass MTC@50b239c1fc8c: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hnbgw-test-latest/ttcn3-tcpdump-stop.sh HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin pass'. Mon Dec 22 12:54:58 UTC 2025 [1;32m====== HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin pass ======[0m Saving talloc report from 127.0.0.1:4261 to HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin.talloc 20251222125458229 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:49156<->l=127.0.0.1:4261 (telnet_interface.c:192) FATAL: command execution failed java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins8870542166860956989.sh java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@51452f67:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-hnbgw-test-latest #1288

1 0

Build failed in Jenkins: ttcn3-pcu-test-latest #2516
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/2516/display/…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 13.66 MiB...] [1;32m20251222125456763 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=520 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456764 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 4 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456777 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=520 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456777 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456778 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456778 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456779 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456779 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456780 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456780 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456780 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456781 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456781 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456781 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456782 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456782 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456783 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456783 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456784 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 8 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456795 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456795 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456797 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456797 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456798 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456798 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456799 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456799 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;34m20251222125456800 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){ASSIGN}: Timeout of X2002 (fsm.c:317)[0;m [1;34m20251222125456800 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){ASSIGN}: Received Event ASSIGN_READY_CCCH (tbf_dl_fsm.c:395)[0;m [1;34m20251222125456800 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){ASSIGN}: state_chg to FLOW (tbf_dl_fsm.c:199)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456801 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456801 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456802 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456802 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456806 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=533 RX RTS.req: sapi=5 arfcn=871 cur_fn=528 block=3 (pcu_l1_if.cpp:583)[0;m [0;36m20251222125456806 [1;34mDRLCMACSCHED[0;m[0;36m [1;34mDEBUG[0;m[0;36m PDCH(bts=0,trx=0,ts=7) FN=533 Scheduling data message at RTS for DL TFI=0 prio=3 mcs_mode_restrict=EGPRS (gprs_rlcmac_sched.cpp:329)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} downlink (V(A)==0 .. V(S)==0) mcs_mode_restrict=EGPRS (tbf_dl.cpp:401)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} Sending new block at BSN 0, CS=CS-3 (tbf_dl.cpp:354)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} Dequeue next LLC (len=10) (tbf_dl.cpp:551)[0;m [1;33m20251222125456806 [1;34mDRLCMACDL[0;m[1;33m [1;34mDEBUG[0;m[1;33m -- Chunk with length 10 is less than remaining space (36): add length header to delimit LLC frame (encoding.cpp:1484)[0;m [1;33m20251222125456806 [1;34mDRLCMACDL[0;m[1;33m [1;34mDEBUG[0;m[1;33m -- Final block, so we done. (encoding.cpp:1518)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} Complete DL frame, len=10 (tbf_dl.cpp:663)[0;m [1;34m20251222125456806 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW}: Received Event LAST_DL_DATA_SENT (tbf_dl.cpp:679)[0;m [1;34m20251222125456806 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW}: state_chg to FINISHED (tbf_dl_fsm.c:244)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} data block (BSN 0, CS-3): 29 d9 aa 84 91 42 fd 5f d5 79 1b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b (tbf_dl.cpp:686)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} need_padding 0 spb_status 0 spb 0 (BSN1 0 BSN2 -1) (tbf_dl.cpp:765)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Copying 1 RLC blocks, 1 BSNs (tbf_dl.cpp:785)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Copying data unit 0 (BSN 0) (tbf_dl.cpp:847)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Scheduling Ack/Nack polling, because it was requested explicitly (e.g. first final block sent). (tbf_dl.cpp:868)[0;m [0;33m20251222125456806 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 533 + 13 = 546 (pdch_ul_controller.c:131)[0;m [0;33m20251222125456806 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Reserving FN 546 for type POLL (pdch_ul_controller.c:184)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Scheduled DL Acknowledgement polling on PACCH (FN=546, TS=7) (tbf_dl.cpp:883)[0;m [1;34m20251222125456806 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Starting timer T3191 [final block (DL-TBF)] with 1 sec. 0 microsec, cur_fn=528 (tbf_dl.cpp:890)[0;m [1;34m20251222125456806 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Scheduled Ack/Nack polling on FN=546, TS=7 (tbf_dl.cpp:903)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} msg block (BSN 0, CS-3): 0f 01 00 29 d9 aa 84 91 42 fd 5f d5 79 1b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 00 (tbf_dl.cpp:911)[0;m [1;32m20251222125456806 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=533 Sending data request: sapi=5 arfcn=871 cur_fn=528 block=3 data=0f 01 00 29 d9 aa 84 91 42 fd 5f d5 79 1b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 00 (pcu_l1_if.cpp:189)[0;m [1;32m20251222125456807 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 13 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456819 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456819 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456823 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456823 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456824 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456824 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456825 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456825 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456826 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456826 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456827 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456827 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456828 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 17 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456838 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456838 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456841 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456841 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456842 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456842 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456843 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456845 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456845 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456845 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456846 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456846 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456847 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 21 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456857 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456857 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456860 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456860 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456861 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456861 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456863 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456863 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456864 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456864 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456866 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 26 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456881 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=541 block=6 data=40 08 10 20 00 00 00 00 00 00 00 20 00 00 00 2b 2b 2b 2b 2b 2b 2b 2b (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456881 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 0 (pcu_l1_if.cpp:485)[0;m [1;36m20251222125456881 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m PDCH(bts=0,trx=0,ts=7) Got RLC block, coding scheme: CS-1, length: 23 (23)) (pdch.cpp:983)[0;m [0;33m20251222125456881 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) FN=546 +++++++++++++++++++++++++ RX : Uplink Control Block +++++++++++++++++++++++++ (pdch.cpp:914)[0;m [1;31m20251222125456881 [1;32mDCSN1[0;m[1;31m [1;32mINFO[0;m[1;31m csnStreamDecoder (type: Pkt DL ACK/NACK (2)): [0;m[1;31mPayloadType = 1 | [0;m[1;31mspare = 0 | [0;m[1;31mR = 0 | [0;m[1;31mMESSAGE_TYPE = 2 | [0;m[1;31mDOWNLINK_TFI = 0 | [0;m[1;31m : Ack_Nack_Description | [0;m[1;31mFINAL_ACK_INDICATION = 1 | [0;m[1;31mSTARTING_SEQUENCE_NUMBER = 1 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[0] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[1] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[2] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[3] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[4] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[5] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[6] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[7] = 1 | [0;m[1;31m: End Ack_Nack_Description | [0;m[1;31mExist_Channel_Request_Description = 0 | [0;m[1;31m : Channel_Quality_Report | [0;m[1;31mC_VALUE = 0 | [0;m[1;31mRXQUAL = 0 | [0;m[1;31mSIGN_VAR = 0 | [0;m[1;31mSlot[0].Exist = 0 | [0;m[1;31mSlot[1].Exist = 0 | [0;m[1;31mSlot[2].Exist = 0 | [0;m[1;31mSlot[3].Exist = 0 | [0;m[1;31mSlot[4].Exist = 0 | [0;m[1;31mSlot[5].Exist = 0 | [0;m[1;31mSlot[6].Exist = 0 | [0;m[1;31mSlot[7].Exist = 0 | [0;m[1;31m: End Channel_Quality_Report | [0;m[1;31mExist_AdditionsR99 = 0 | [0;m[1;31mPadding = [0;m[1;31m0|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m (gsm_rlcmac.c:5472)[0;m [0;33m20251222125456882 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) FN=546 ------------------------- RX : Uplink Control Block ------------------------- (pdch.cpp:927)[0;m [1;34m20251222125456882 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} RX: [PCU <- BTS] Packet Downlink Ack/Nack (pdch.cpp:501)[0;m [0;33m20251222125456882 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Got GPRS DL ACK bitmap: SSN: 1, BSN 0 to 1 - 1 (1 blocks), "R" (pdch.cpp:511)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} downlink acknowledge (tbf_dl.cpp:1085)[0;m [1;34m20251222125456882 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} stopping timer T3191 [ACK/NACK received] (tbf.cpp:377)[0;m BTS(202)@b4f2b99cd73c: Shutting down virtual BTS #0... [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} ack: (BSN=0)"R"(BSN=0) R=ACK I=NACK (tbf_dl.cpp:1034)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} DL analysis, range=0:1, lost=0, recv=1, skipped=0, bsn=0, info='R...............................................................' (tbf_dl.cpp:998)[0;m [1;33m20251222125456882 [1;34mDRLCMACDL[0;m[1;33m [1;34mDEBUG[0;m[1;33m - got ack for BSN=0 (rlc_window_dl.cpp:103)[0;m [1;31m20251222125456882 [1;34mDRLCMACMEAS[0;m[1;31m [1;34mDEBUG[0;m[1;31m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} DL Loss: Received: 1 Lost: 0 Sum: 1 (gprs_rlcmac_meas.cpp:127)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} V(B): (V(A)=1)""(V(S)-1=0) A=Acked N=Nacked U=Unacked X=Resend-Unacked I=Invalid (tbf_dl.cpp:1058)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Final ACK received. (tbf_dl.cpp:1105)[0;m [1;34m20251222125456882 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED}: Received Event FINAL_ACK_RECVD (tbf_dl.cpp:1076)[0;m [1;34m20251222125456882 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED}: state_chg to WAIT_RELEASE (tbf_dl_fsm.c:264)[0;m [1;34m20251222125456882 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE} unset ass. type CCCH [prev CCCH:1, PACCH:0] (tbf_dl_fsm.c:67)[0;m [1;32m20251222125456882 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456882 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456883 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456883 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456884 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456884 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456885 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456885 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m 20251222125456885 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:53894<->l=127.0.0.1:4240 (telnet_interface.c:138) [1;32m20251222125456886 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456886 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456887 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456887 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456888 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456888 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m 20251222125456892 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:53880<->l=127.0.0.1:4240 (telnet_interface.c:138) [1;32m20251222125456894 [1;31mDL1IF[0;m[1;32m [1;31mERROR[0;m[1;32m pcu_sock_read: recv() failed with rc=0 errno=9 (pcuif_sock.c:123)[0;m [1;32m20251222125456894 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m PCU socket has LOST connection (pcuif_sock.c:78)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [1;34m20251222125456894 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE} free (tbf.cpp:271)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE}, 1 TBFs, USFs = 00, TFIs = 00000001. (pdch.cpp:1202)[0;m [1;34m20251222125456894 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(IMSI-262420000000001:TLLI-0x00000001:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE} (gprs_ms.c:381)[0;m [1;34m20251222125456894 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(IMSI-262420000000001:TLLI-0x00000001:TA-220:MSCLS-0-0): - tbf: now used by 0 (-) (gprs_ms.c:394)[0;m [1;34m20251222125456894 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(IMSI-262420000000001:TLLI-0x00000001:TA-220:MSCLS-0-0) Schedule MS release in 60 secs (gprs_ms.c:246)[0;m [1;34m20251222125456894 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m ********** DL-TBF ends here ********** (tbf.cpp:279)[0;m [1;34m20251222125456894 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE}: Deallocated (fsm.c:568)[0;m [1;34m20251222125456894 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){NONE}: Deallocated (fsm.c:568)[0;m [1;34m20251222125456894 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){NONE}: Deallocated (fsm.c:568)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=3,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=4,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=5,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=6,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=7,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [38;5;57m20251222125456894 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251222125456894 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 became unavailable (gprs_bssgp_pcu.c:690)[0;m [38;5;57m20251222125456894 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(01234) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222125456894 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251222125456894 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251222125456895 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251222125456895 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Deallocated (fsm.c:568)[0;m respawn: 21: stopped pid 8220 with status 0 MTC@b4f2b99cd73c: Test case TC_zero_x2031_t3191 finished. Verdict: pass MTC@b4f2b99cd73c: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-latest/ttcn3-tcpdump-stop.sh PCU_Tests.TC_zero_x2031_t3191 pass'. respawn: 22: starting: osmo-pcu Mon Dec 22 12:54:56 UTC 2025 [1;32m====== PCU_Tests.TC_zero_x2031_t3191 pass ======[0m Saving talloc report from 127.0.0.1:4240 to PCU_Tests.TC_zero_x2031_t3191.talloc 20251222125456943 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Setting up GSMTAP Um forwarding to '127.0.0.1:4729' (pcu_main.cpp:298) 20251222125456944 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Available via telnet 127.0.0.1 4240 (telnet_interface.c:88) [1;32m20251222125456944 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:202)[0;m [1;32m20251222125456944 [1;31mDL1IF[0;m[1;32m [1;31mERROR[0;m[1;32m Failed to connect to the BTS (/tmp/pcu_bts). Retrying... (pcuif_sock.c:210)[0;m 20251222125456973 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:53900<->l=127.0.0.1:4240 (telnet_interface.c:192) 20251222125457074 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:53900<->l=127.0.0.1:4240 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=837076) Waiting for packet dumper to finish... 1 (prev_count=837076, count=851424) FATAL: command execution failed java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins3602645729785274613.sh java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@51452f67:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure FATAL: Channel "hudson.remoting.Channel@51452f67:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@51452f67:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.Launcher$RemoteLauncher.kill(Launcher.java:1147) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:539) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Recording test results Agent went offline during the build ERROR: Connection was broken Build step 'Publish JUnit test result report' marked build as failure

1 0

Jenkins build became unstable: ttcn3-bsc-test-latest #2647
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/2647/display/…>

1 0

Build failed in Jenkins: Osmocom_OBS_master #124237
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/124237/display/r…> Changes: ------------------------------------------ Started by upstream project "master-gapk" build number 2769 originally caused by: Started by timer Running as SYSTEM Building remotely on build5-deb12build-ansible (obs ttcn3_with_linux_6.1_or_higher qemu registry-build-amd64 ttcn3 osmo-gsm-tester-build ttcn3-ggsn-test-kernel-git io_uring osmocom-gerrit coverity osmocom-master) in workspace <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/> The recommended git tool is: NONE No credentials specified Wiping out workspace first. Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-ci > git init <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/> # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-ci > git --version # timeout=10 > git --version # 'git version 2.39.5' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-ci +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url https://gerrit.osmocom.org/osmo-ci # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch > git rev-parse origin/master^{commit} # timeout=10 Checking out Revision bb15a95e3843864b853e6d1747b82b08556a1923 (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 Commit message: "jobs/ttcn3-testsuites: set RUN_BPFTRACE=1" > git rev-list --no-walk bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 [Osmocom_OBS_master] $ /bin/sh -xe /tmp/jenkins9939039595080972234.sh + export PYTHONUNBUFFERED=1 + ./scripts/obs/update_obs_project.py --apiurl https://obs.osmocom.org --conflict-version --docker --feed master --meta osmocom:master docker: building image debian-13-osmocom-obs-build-srcpkg + run_in_docker_and_exit(): ['docker', 'build', '--build-arg', 'DISTRO=debian:13', '--build-arg', 'DISTRO_FROM=debian:trixie', '--build-arg', 'UID=1000', '-t', 'debian-13-osmocom-obs-build-srcpkg', '-f', '<https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/scripts/obs/d…',> '<https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/scripts/obs/d…']> #0 building with "default" instance using docker driver #1 [internal] load build definition from build_srcpkg.Dockerfile #1 transferring dockerfile: 1.45kB done #1 WARN: InvalidDefaultArgInFrom: Default value for ARG ${DISTRO_FROM} results in empty or invalid base image name (line 3) #1 DONE 0.1s #2 [internal] load metadata for docker.io/library/debian:trixie #2 DONE 0.4s #3 [internal] load .dockerignore #3 transferring context: 2B done #3 DONE 0.1s #4 [1/4] FROM docker.io/library/debian:trixie@sha256:0d01188e8dd0ac63bf155900fad49279131a876a1ea7fac917c62e87ccb2732d #4 DONE 0.0s #5 [2/4] RUN APT-GET update && apt-get upgrade -y && apt-get install -y --no-install-recommends ca-certificates colordiff debhelper default-libmysqlclient-dev dh-python dh-virtualenv dpkg-dev erlang-nox fakeroot git git-review gnupg2 libxml2-utils lsb-release meson osc pkgconf python3-packaging python3-pip python3-setuptools quilt sed sphinx-common wget && apt-get clean #5 CACHED #6 [3/4] RUN WGET https://github.com/erlang/rebar3/releases/download/3.24.0/rebar3 -O /usr/bin/rebar3 && echo "d2d31cfb98904b8e4917300a75f870de12cb5167cd6214d1043e973a56668a54 /usr/bin/rebar3" | sha256sum -c && chmod +x /usr/bin/rebar3 && rebar3 --version #6 CACHED #7 [4/4] RUN USERADD --uid=1000 -m user #7 CACHED #8 exporting to image #8 exporting layers done #8 writing image sha256:6c263b1ef9ecdf31472824a0f1d0634380b59e435d64a36c5721304914148c9e done #8 naming to docker.io/library/debian-13-osmocom-obs-build-srcpkg done #8 DONE 0.0s [33m1 warning found (use docker --debug to expand): [0m - InvalidDefaultArgInFrom: Default value for ARG ${DISTRO_FROM} results in empty or invalid base image name (line 3) docker: running: update_obs_project.py inside docker /obs/lib/debian.py:183: SyntaxWarning: invalid escape sequence '\/' lib.run_cmd(["sed", "-i", "s/$(MAKE) -C doc\/manuals//g", f"{debian}/rules"]) OBS: getting packages in osmocom:master ERROR: command failed unexpectedly *** command *** ['osc', '-A', 'https://obs.osmocom.org', 'list', 'osmocom:master'] *** returncode *** 1 *** output *** fatal: not a git repository (or any of the parent directories): .git SSL Error: [SSL: UNEXPECTED_EOF_WHILE_READING] EOF occurred in violation of protocol (_ssl.c:1029) *** python trace *** Traceback (most recent call last): File "/obs/update_obs_project.py", line 265, in <module> main() ~~~~^^ File "/obs/update_obs_project.py", line 257, in main pkgs_remote = lib.osc.get_remote_pkgs() File "/obs/lib/osc.py", line 63, in get_remote_pkgs ret = run_osc(["list", proj]) File "/obs/lib/osc.py", line 58, in run_osc return lib.run_cmd(cmd, *args, **kwargs) ~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ File "/obs/lib/__init__.py", line 202, in run_cmd exit_error_cmd(p, "command failed unexpectedly") ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/obs/lib/__init__.py", line 163, in exit_error_cmd raise RuntimeError("shell command related error, find details right above" " this python trace") RuntimeError: shell command related error, find details right above this python trace Build step 'Execute shell' marked build as failure

1 1

Build failed in Jenkins: update-osmo-ci-on-slaves » lx2-raspbian12build-ansible #3197
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=lx2-…> Changes: ------------------------------------------ Started by upstream project "update-osmo-ci-on-slaves" build number 3197 originally caused by: Started by timer Running as SYSTEM Building remotely on lx2-raspbian12build-ansible (registry-build-arm64 raspbian) in workspace /home/osmocom-build/jenkins/workspace/update-osmo-ci-on-slaves/label/lx2-raspbian12build-ansible The recommended git tool is: NONE No credentials specified Wiping out workspace first. Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-ci > git init /home/osmocom-build/jenkins/workspace/update-osmo-ci-on-slaves/label/lx2-raspbian12build-ansible # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-ci > git --version # timeout=10 > git --version # 'git version 2.39.5' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-ci +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url https://gerrit.osmocom.org/osmo-ci # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch Checking out Revision bb15a95e3843864b853e6d1747b82b08556a1923 (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 Commit message: "jobs/ttcn3-testsuites: set RUN_BPFTRACE=1" > git rev-list --no-walk bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 FATAL: Unable to delete script file /tmp/jenkins10155043076686033045.sh java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@50b49bbd:lx2-raspbian12build-ansible": Remote call on lx2-raspbian12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at PluginClassLoader for matrix-project//hudson.matrix.MatrixRun.run(MatrixRun.java:153) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Agent went offline during the build ERROR: Connection was broken Build step 'Execute shell' marked build as failure

1 0

Build failed in Jenkins: update-osmo-ci-on-slaves » rpi4-raspbian12build-ansible-1 #3197
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…> Changes: ------------------------------------------ [...truncated 12.55 KiB...] ---> Using cache ---> 76e5951902fd Step 12/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/python/osmo-python-tests osmo-python-tests && cd osmo-python-tests && python3 setup.py clean build install && cd .. && rm -rf osmo-python-tests ---> Using cache ---> d3a5c7d32d3b Step 13/16 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ci/+/master?format=TEXT /tmp/osmo-ci-commit ---> Using cache ---> b6591f1db2f0 Step 14/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/osmo-ci osmo-ci && su build -c "cd osmo-ci/scripts && cp -v *.sh *.py /usr/local/bin" && rm -rf osmo-ci ---> Using cache ---> 6be571e37d1a Step 15/16 : ADD $OSMOCOM_REPO/Release /tmp/Release ---> e7be9f2b88d3 Step 16/16 : RUN set -x && echo "deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] $OSMOCOM_REPO ./" > /etc/apt/sources.list.d/osmocom-nightly.list && apt-get update && apt-get install -y --no-install-recommends liblimesuite-dev libuhd-dev && apt-get clean ---> Running in d1fbf6a053b0 [91m+ echo deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12/ ./ [0m[91m+ apt-get update [0mHit:1 http://deb.debian.org/debian bookworm InRelease Get:2 http://deb.debian.org/debian bookworm-updates InRelease [55.4 kB] Get:3 http://deb.debian.org/debian-security bookworm-security InRelease [48.0 kB] Get:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ InRelease [1620 B] Get:5 http://deb.debian.org/debian-security bookworm-security/main armhf Packages [270 kB] Get:6 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ Packages [207 kB] Fetched 582 kB in 2s (296 kB/s) Reading package lists... [91m+ apt-get install -y --no-install-recommends liblimesuite-dev libuhd-dev [0mReading package lists... Building dependency tree... Reading state information... The following additional packages will be installed: liblimesuite22.09-1 libpython3.11 libuhd4.3.0 Suggested packages: liblimesuite-doc uhd-doc uhd-host Recommended packages: limesuite-udev gnuradio-dev The following NEW packages will be installed: liblimesuite-dev liblimesuite22.09-1 libpython3.11 libuhd-dev libuhd4.3.0 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. Need to get 5117 kB of archives. After this operation, 15.8 MB of additional disk space will be used. Get:1 http://deb.debian.org/debian bookworm/main armhf liblimesuite22.09-1 armhf 22.09.1+dfsg-1+b2 [222 kB] Get:2 http://deb.debian.org/debian bookworm/main armhf liblimesuite-dev armhf 22.09.1+dfsg-1+b2 [55.8 kB] Get:3 http://deb.debian.org/debian bookworm/main armhf libpython3.11 armhf 3.11.2-6+deb12u6 [1709 kB] Get:4 http://deb.debian.org/debian bookworm/main armhf libuhd4.3.0 armhf 4.3.0.0+ds1-5 [2912 kB] Get:5 http://deb.debian.org/debian bookworm/main armhf libuhd-dev armhf 4.3.0.0+ds1-5 [219 kB] [91mdebconf: delaying package configuration, since apt-utils is not installed [0mFetched 5117 kB in 3s (1506 kB/s) Selecting previously unselected package liblimesuite22.09-1:armhf. (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 32158 files and directories currently installed.) Preparing to unpack .../liblimesuite22.09-1_22.09.1+dfsg-1+b2_armhf.deb ... Unpacking liblimesuite22.09-1:armhf (22.09.1+dfsg-1+b2) ... Selecting previously unselected package liblimesuite-dev:armhf. Preparing to unpack .../liblimesuite-dev_22.09.1+dfsg-1+b2_armhf.deb ... Unpacking liblimesuite-dev:armhf (22.09.1+dfsg-1+b2) ... Selecting previously unselected package libpython3.11:armhf. Preparing to unpack .../libpython3.11_3.11.2-6+deb12u6_armhf.deb ... Unpacking libpython3.11:armhf (3.11.2-6+deb12u6) ... Selecting previously unselected package libuhd4.3.0:armhf. Preparing to unpack .../libuhd4.3.0_4.3.0.0+ds1-5_armhf.deb ... Unpacking libuhd4.3.0:armhf (4.3.0.0+ds1-5) ... Selecting previously unselected package libuhd-dev:armhf. Preparing to unpack .../libuhd-dev_4.3.0.0+ds1-5_armhf.deb ... Unpacking libuhd-dev:armhf (4.3.0.0+ds1-5) ... Setting up libpython3.11:armhf (3.11.2-6+deb12u6) ... Setting up libuhd4.3.0:armhf (4.3.0.0+ds1-5) ... Setting up liblimesuite22.09-1:armhf (22.09.1+dfsg-1+b2) ... Setting up libuhd-dev:armhf (4.3.0.0+ds1-5) ... Setting up liblimesuite-dev:armhf (22.09.1+dfsg-1+b2) ... Processing triggers for libc-bin (2.36-9+deb12u13) ... [91m+ apt-get clean [0mRemoving intermediate container d1fbf6a053b0 ---> 800ab78d7125 [Warning] One or more build-args [OSMO_SIP_BRANCH USER OPEN5GS_BRANCH OSMO_GGSN_BRANCH OSMO_HNBGW_BRANCH OSMO_IUH_BRANCH OSMO_PCU_BRANCH OSMO_SGSN_BRANCH OSMO_STP_BRANCH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_HNODEB_BRANCH OSMO_NITB_BRANCH PJPROJECT_BRANCH M3UA_TESTTOOL_BRANCH OSMOCOM_REPO_VERSION OSMO_BB_BRANCH OSMO_S1GW_BRANCH OSMO_UECUPS_BRANCH OSMO_BSC_BRANCH OSMO_BTS_BRANCH OSMO_MSC_BRANCH OSMO_REMSIM_BRANCH OSMO_TTCN3_BRANCH ASTERISK_BRANCH LIBOSMOCORE_BRANCH OSMO_CBC_BRANCH OSMO_DIA2GSUP_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_HLR_BRANCH DISTRO OSMO_GBPROXY_BRANCH OSMO_MGW_BRANCH OSMO_EPDG_BRANCH] were not consumed Successfully built 800ab78d7125 Successfully tagged osmocom-build/debian-bookworm-build-arm:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…'> + docker_image_exists debian-bookworm-build-arm + docker images -q osmocom-build/debian-bookworm-build-arm + test -n 800ab78d7125 + list_osmo_packages debian-bookworm debian-bookworm-build-arm + local distro=debian-bookworm + local image=debian-bookworm-build-arm + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/debian-bookworm-build-arm -c + [ -n ] + return + [ = registry.osmocom.org ] + docker_depends debian-trixie-build-arm + local feed + depends= + [ -n ] + docker_distro_from_image_name debian-trixie-build-arm + echo debian-trixie + distro_arg=debian-trixie + [ -z ] + docker_upstream_distro_from_image_name debian-trixie-build-arm + echo debian:trixie + upstream_distro_arg=debian:trixie + docker_dir_from_image_name debian-trixie-build-arm + echo debian-trixie-build-arm + dir=debian-trixie-build-arm + [ -z 1 ] + set +x Building image: debian-trixie-build-arm (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + docker_osmo_ttcn3_branch + [ -n ] + echo master + make -C ../debian-trixie-build-arm BUILD_ARGS= UPSTREAM_DISTRO=debian:trixie DISTRO=debian-trixie IMAGE=osmocom-build/debian-trixie-build-arm OSMO_TTCN3_BRANCH=master make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…'> awk: cannot open .release (No such file or directory) awk: cannot open .release (No such file or directory) awk: cannot open .release (No such file or directory) rm -rf .common cp -r <https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…> .common INFO: .release created release=0.0.0 docker build \ --build-arg USER=osmocom-build \ --build-arg UID=1000 \ --build-arg REGISTRY=docker.io \ --build-arg OSMO_TTCN3_BRANCH=master \ --build-arg UPSTREAM_DISTRO=debian:trixie \ --build-arg DISTRO=debian-trixie \ --build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \ --build-arg OSMOCOM_REPO_PATH=packages/osmocom: \ --build-arg OSMOCOM_REPO_VERSION=latest \ --build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \ --build-arg ASTERISK_BRANCH=sysmocom/20.7.0 \ --build-arg LIBOSMOCORE_BRANCH=master \ --build-arg M3UA_TESTTOOL_BRANCH=osmocom/master \ --build-arg OSMO_BB_BRANCH=master \ --build-arg OSMO_BSC_BRANCH=master \ --build-arg OSMO_BTS_BRANCH=master \ --build-arg OSMO_CBC_BRANCH=master \ --build-arg OSMO_DIA2GSUP_BRANCH=master \ --build-arg OSMO_EPDG_BRANCH=master \ --build-arg OSMO_GBPROXY_BRANCH=master \ --build-arg OSMO_GGSN_BRANCH=master \ --build-arg OSMO_GSM_TESTER_BRANCH=master \ --build-arg OSMO_HLR_BRANCH=master \ --build-arg OSMO_HNBGW_BRANCH=master \ --build-arg OSMO_HNODEB_BRANCH=master \ --build-arg OSMO_IUH_BRANCH=master \ --build-arg OSMO_MGW_BRANCH=master \ --build-arg OSMO_MSC_BRANCH=master \ --build-arg OSMO_NITB_BRANCH=master \ --build-arg OSMO_PCU_BRANCH=master \ --build-arg OSMO_REMSIM_BRANCH=master \ --build-arg OSMO_S1GW_BRANCH=master \ --build-arg OSMO_SGSN_BRANCH=master \ --build-arg OSMO_SIP_BRANCH=master \ --build-arg OSMO_STP_BRANCH=master \ --build-arg OSMO_UECUPS_BRANCH=master \ --build-arg OPEN5GS_BRANCH=main \ --build-arg PJPROJECT_BRANCH=sysmocom/2.14 \ -t osmocom-build/debian-trixie-build-arm:latest . Sending build context to Docker daemon 37.89kB Step 1/16 : ARG REGISTRY=docker.io Step 2/16 : ARG UPSTREAM_DISTRO=debian:trixie Step 3/16 : FROM ${REGISTRY}/arm32v7/${UPSTREAM_DISTRO} ---> 458df24d5ca1 Step 4/16 : ARG OSMOCOM_REPO_MIRROR="https://downloads.osmocom.org" ---> Using cache ---> e645b9979391 Step 5/16 : ARG OSMOCOM_REPO_PATH="packages/osmocom:" ---> Using cache ---> 97b305b7e08f Step 6/16 : ARG OSMOCOM_REPO="${OSMOCOM_REPO_MIRROR}/${OSMOCOM_REPO_PATH}/nightly/Debian_13/" ---> Using cache ---> 8d86f5443406 Step 7/16 : ARG UID ---> Using cache ---> c3bef72dc2da Step 8/16 : COPY .common/Release.key /etc/apt/trusted.gpg.d/obs.osmocom.org.asc ---> Using cache ---> b5bc42f7462e Step 9/16 : RUN set -x && useradd --uid=${UID} -d /build -m build && chown -R build:build /usr/local && echo "path-exclude=/usr/share/man/*" > /etc/dpkg/dpkg.cfg.d/exclude-man-pages && rm -rf /usr/share/man/ ---> Using cache ---> 962bea47dce4 Step 10/16 : RUN set -x && apt-get update && apt-get install -y --no-install-recommends autoconf automake bzip2 ca-certificates ccache g++ gcc git libbladerf-dev libboost-dev libboost-filesystem-dev libboost-program-options-dev libboost-thread-dev libfftw3-dev libgnutls28-dev liblimesuite-dev libmnl-dev libortp-dev libpcsclite-dev libsctp-dev libtalloc-dev libtool libuhd-dev liburing-dev libusb-1.0-0-dev make pkg-config python3-minimal python3-setuptools stow && apt-get clean ---> Using cache ---> f15cd082d96d Step 11/16 : ADD https://gerrit.osmocom.org/plugins/gitiles/python/osmo-python-tests/+/maste… /tmp/osmo-python-tests-commit ---> Using cache ---> c6b8a3d2af5b Step 12/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/python/osmo-python-tests osmo-python-tests && cd osmo-python-tests && python3 setup.py clean build install && cd .. && rm -rf osmo-python-tests ---> Using cache ---> 4c0c5105eb1e Step 13/16 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ci/+/master?format=TEXT /tmp/osmo-ci-commit ---> Using cache ---> 959887850414 Step 14/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/osmo-ci osmo-ci && su build -c "cd osmo-ci/scripts && cp -v *.sh *.py /usr/local/bin" && rm -rf osmo-ci ---> Using cache ---> 30210545af9e Step 15/16 : ADD $OSMOCOM_REPO/Release /tmp/Release ---> c01779409743 Step 16/16 : RUN set -x && echo "deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] $OSMOCOM_REPO ./" > /etc/apt/sources.list.d/osmocom-nightly.list && apt-get update && apt-get install -y --no-install-recommends -t'o=obs://osmocom/osmocom:nightly/Debian_13,n=Debian_13,l=osmocom:nightly,c=' sdcc && apt-get clean ---> Running in 0721498e0bbc [91m+ echo deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13/ ./ + apt-get update [0mHit:1 http://deb.debian.org/debian trixie InRelease Get:2 http://deb.debian.org/debian trixie-updates InRelease [47.3 kB] Get:3 http://deb.debian.org/debian-security trixie-security InRelease [43.4 kB] Ign:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ InRelease Get:5 http://deb.debian.org/debian-security trixie-security/main armhf Packages [87.9 kB] Get:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ InRelease [1620 B] Get:6 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ Packages [204 kB] Fetched 385 kB in 2s (228 kB/s) Reading package lists... [91m+ apt-get install -y --no-install-recommends -to=obs://osmocom/osmocom:nightly/Debian_13,n=Debian_13,l=osmocom:nightly,c= sdcc [0mReading package lists... Building dependency tree... Reading state information... The following additional packages will be installed: sdcc-libraries Suggested packages: python sdcc-ucsim Recommended packages: gputils The following NEW packages will be installed: sdcc sdcc-libraries 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. Need to get 3622 kB of archives. After this operation, 26.2 MB of additional disk space will be used. Ign:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd Ign:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd Ign:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd Ign:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd Ign:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd Ign:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd Err:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd SSL connection failed: error:0A000126:SSL routines::unexpected eof while reading / Success [IP: 148.251.236.141 443] Get:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd [1584 kB] Fetched 1584 kB in 8s (195 kB/s) [91mE: Failed to fetch https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13/./armhf/s… SSL connection failed: error:0A000126:SSL routines::unexpected eof while reading / Success [IP: 148.251.236.141 443] E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing? [0mThe command '/bin/sh -c set -x && echo "deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] $OSMOCOM_REPO ./" > /etc/apt/sources.list.d/osmocom-nightly.list && apt-get update && apt-get install -y --no-install-recommends -t'o=obs://osmocom/osmocom:nightly/Debian_13,n=Debian_13,l=osmocom:nightly,c=' sdcc && apt-get clean' returned a non-zero code: 100 make: *** [../make/Makefile:97: docker-build] Error 100 make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…'> + exit 1 Build step 'Execute shell' marked build as failure

1 0

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications December 2025