- jenkins-notifications - lists.osmocom.org

Build failed in Jenkins: ttcn3-pcu-test-sns-asan #425
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns-asan/425/display…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 459.75 KiB...] [1;35m20251222164034039 [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;57m20251222164034039 [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;57m20251222164034039 [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;57m20251222164034039 [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 [38;5;57m20251222164034039 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164034039 [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;57m20251222164034039 [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:23001){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251222164034039 [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:23001){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251222164034040 [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:23001){UNBLOCKED}: Deallocated (fsm.c:568)[0;m [38;5;57m20251222164034040 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164034040 [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;57m20251222164034040 [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:23002){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251222164034040 [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:23002){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251222164034040 [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:23002){UNBLOCKED}: Deallocated (fsm.c:568)[0;m MTC@613b72e27ae4: Test case TC_sns_add finished. Verdict: pass MTC@613b72e27ae4: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_add pass'. Mon Dec 22 16:40:34 UTC 2025 [1;32m====== PCU_Tests_SNS.TC_sns_add pass ======[0m Saving talloc report from 127.0.0.1:4240 to PCU_Tests_SNS.TC_sns_add.talloc respawn: 11: stopped pid 7579 with status 0 respawn: 12: starting: osmo-pcu -c osmo-pcu-sns.cfg % 'alpha <0-10>' is now deprecated: use osmo-bsc's 'gprs power-control alpha <0-10>' instead 20251222164034075 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Available via telnet 127.0.0.1 4240 (telnet_interface.c:88) [1;32m20251222164034075 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:204)[0;m [1;32m20251222164034075 [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:212)[0;m 20251222164034076 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:54800<->l=127.0.0.1:4240 (telnet_interface.c:192) 20251222164034077 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:54800<->l=127.0.0.1:4240 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=36008) Waiting for packet dumper to finish... 1 (prev_count=36008, count=63100) MTC@613b72e27ae4: External command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_add pass' was executed successfully (exit status: 0). MTC@613b72e27ae4: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_add_nack'. ------ PCU_Tests_SNS.TC_sns_add_nack ------ Mon Dec 22 16:40:36 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/logs/testsuite/PCU_Tests_SNS.TC_sns_add_nack.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/logs/testsuite/PCU_Tests_SNS.TC_sns_add_nack.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@613b72e27ae4: External command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_add_nack' was executed successfully (exit status: 0). MTC@613b72e27ae4: Test case TC_sns_add_nack started. MTC@613b72e27ae4: Start timer g_T_guard: 60 s MTC@613b72e27ae4: Start timer T: 5 s [1;32m20251222164039076 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:204)[0;m [1;32m20251222164039077 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m osmo-bts PCU socket /tmp/pcu_bts has been connected (pcuif_sock.c:222)[0;m [1;32m20251222164039077 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Sending 1.5.2.1-c4d5d.202512212029~asan TXT as PCU_VERSION to BTS (pcu_l1_if.cpp:152)[0;m [1;32m20251222164039083 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m Received message for new BTS0 (pcu_l1_if.cpp:1246)[0;m 20251222164039084 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55b0c60be1a0(bts) with 102 counters (rate_ctr.c:86) [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Info indication received: (pcu_l1_if.cpp:775)[0;m [1;32m20251222164039084 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS available (pcu_l1_if.cpp:801)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m mcc=262 (pcu_l1_if.cpp:802)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m mnc=42 (pcu_l1_if.cpp:803)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m lac=13135 (pcu_l1_if.cpp:804)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m rac=0 (pcu_l1_if.cpp:805)[0;m MTC@613b72e27ae4: f_incoming_sns_size(idx=0) MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Size := { nsPduType := '12'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, resetFlag := { iEI := '0A'O ("\n"), resetBIT := '1'B, spare := '0000000'B }, maxNumberOfNSVCs := { iEI := '07'O ("\a"), maxNumberOfNSVCs := ? }, numberOfIP4_Endpoints := ?, numberOfIP6_Endpoints := omit } } [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cell_id=20960 (pcu_l1_if.cpp:806)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m bsic=7 (pcu_l1_if.cpp:807)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m nsei=1234 (pcu_l1_if.cpp:808)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m nse_timer=3 3 3 3 30 3 10 (pcu_l1_if.cpp:809)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cell_timer=3 3 3 3 3 10 3 10 3 10 3 (pcu_l1_if.cpp:814)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m repeat_time=250 (pcu_l1_if.cpp:822)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m repeat_count=3 (pcu_l1_if.cpp:823)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m bvci=1234 (pcu_l1_if.cpp:824)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3142=20 (pcu_l1_if.cpp:825)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3169=5 (pcu_l1_if.cpp:826)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3191=5 (pcu_l1_if.cpp:827)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3193=1600 (ms) (pcu_l1_if.cpp:828)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3195=5 (pcu_l1_if.cpp:829)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3101=10 (pcu_l1_if.cpp:830)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3103=4 (pcu_l1_if.cpp:831)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3105=8 (pcu_l1_if.cpp:832)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cv_countdown=15 (pcu_l1_if.cpp:833)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m dl_tbf_ext=2500 (pcu_l1_if.cpp:834)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m ul_tbf_ext=2500 (pcu_l1_if.cpp:835)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS1 (pcu_l1_if.cpp:849)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS2 (pcu_l1_if.cpp:849)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS3 (pcu_l1_if.cpp:849)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS4 (pcu_l1_if.cpp:849)[0;m [0;33m20251222164039085 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max CS: DL=4 UL=4 (bts.cpp:1422)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS1 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS2 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS3 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS4 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS5 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS6 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS7 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS8 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS9 (pcu_l1_if.cpp:858)[0;m [0;33m20251222164039085 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max MCS: DL=9 UL=9 (bts.cpp:1449)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_cs=2 (VTY forced, ignoring) (pcu_l1_if.cpp:863)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_mcs=1 (pcu_l1_if.cpp:868)[0;m 20251222164039085 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74de0a0(bssgp:bss_ctx) with 7 counters (rate_ctr.c:86) 20251222164039085 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55b0c60bd5a0(pcu:sgsn) with 2 counters (rate_ctr.c:86) [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 nsvci=1234 (pcu_l1_if.cpp:715)[0;m [1;32m20251222164039086 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 address: r=127.0.0.1:23000<->l=NULL:22000 (pcu_l1_if.cpp:719)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Allocated (fsm.c:456)[0;m 20251222164039086 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dee00(ns:nse) with 15 counters (rate_ctr.c:86) [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_ADD_BIND (gprs_ns2_sns.c:2680)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2028)[0;m [38;5;57m20251222164039086 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS no endpoints, transfer=0, first=1, mtu=0 (gprs_ns2.c:575)[0;m [1;35m20251222164039086 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSE SNS no endpoints / 6 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to UNCONFIGURED (gprs_ns2_sns.c:2240)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2513)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to BSS_SIZE (gprs_ns2_sns.c:2255)[0;m 20251222164039086 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dedc0(ns:nsvc) with 15 counters (rate_ctr.c:86) [38;5;57m20251222164039086 [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){UNCONFIGURED}: Allocated (fsm.c:456)[0;m [38;5;63m20251222164039086 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-SIZE (reset=1, max_nr_nsvc=8192, num_ip4=1, num_ip6=-1) (gprs_ns2_message.c:807)[0;m [1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=0,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039086 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=0, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=1,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=1, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=2,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=2, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=3,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=3,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=3, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=4,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=4,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=4, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=5,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=5,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=5, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=6,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=6,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=6, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=7,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=7,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=7, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS model: osmo-bts-trx (pcu_l1_if.cpp:974)[0;m MTC@613b72e27ae4: f_incoming_sns_config(idx=0) MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Config := { nsPduType := '0F'O, endFlag := { eBIT := '1'B, spare := '0000000'B }, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, listofIP4Elements := { iEI := '05'O, ext := '1'B, lengthIndicator := { length1 := ? }, iP4_Elements := { { ipAddress := '7F000001'O, uDP_Port := '55F0'O, signallingWeight := ?, dataWeight := ? } } }, listofIP6Elements := omit } } [38;5;63m20251222164039093 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-SIZE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039093 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: NSEI=1234 Rx SNS PDU type SNS-SIZE-ACK (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251222164039094 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: Received Event RX_SIZE_ACK (gprs_ns2_sns.c:2349)[0;m [38;5;57m20251222164039094 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: state_chg to BSS_CONFIG_BSS (gprs_ns2_sns.c:827)[0;m [38;5;63m20251222164039094 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG (end_flag=1, num_ip4=1, num_ip6=0) (gprs_ns2_message.c:713)[0;m MTC@613b72e27ae4: f_outgoing_sns_config_1c1u(idx=0) MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, causeNS := omit } } [38;5;63m20251222164039096 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: NSEI=1234 Rx SNS PDU type SNS-CONFIG-ACK (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: Received Event RX_CONFIG_ACK (gprs_ns2_sns.c:2358)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: state_chg to BSS_CONFIG_SGSN (gprs_ns2_sns.c:1059)[0;m [38;5;63m20251222164039096 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: NSEI=1234 Rx SNS PDU type SNS-CONFIG (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Received Event RX_CONFIG_END (gprs_ns2_sns.c:2353)[0;m [38;5;57m20251222164039097 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Rx SNS-CONFIG: Remote IPv4 list now 2 entries (gprs_ns2_sns.c:1126)[0;m 20251222164039097 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dedc0(ns:nsvc) with 15 counters (rate_ctr.c:86) [38;5;57m20251222164039097 [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:23001){UNCONFIGURED}: Allocated (fsm.c:456)[0;m [38;5;57m20251222164039097 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (cause=NULL) (gprs_ns2_message.c:755)[0;m [38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (gprs_ns2_message.c:757)[0;m [38;5;57m20251222164039097 [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){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m [38;5;57m20251222164039097 [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){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m [38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=0, first=1, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164039097 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20251222164039097 [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}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m [38;5;57m20251222164039097 [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:23001){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m [38;5;57m20251222164039097 [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:23001){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m [38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=1, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251222164039097 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 became available (gprs_bssgp_pcu.c:681)[0;m [38;5;59m20251222164039097 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending reset on BVCI 0 (gprs_bssgp_pcu.c:1045)[0;m [38;5;59m20251222164039097 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=0) Tx BVC-RESET CAUSE=O&M intervention (gprs_bssgp.c:124)[0;m [38;5;61m20251222164039097 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164039097 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20251222164039097 [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:23001){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m [38;5;57m20251222164039098 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: state_chg to CONFIGURED (gprs_ns2_sns.c:1195)[0;m [38;5;57m20251222164039098 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS configured, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251222164039098 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 SNS configured. (gprs_bssgp_pcu.c:678)[0;m [38;5;63m20251222164039099 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039100 [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}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039101 [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}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039101 [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:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251222164039102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039102 [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:23001){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20251222164039102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164049100 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164049102 [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}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164049102 [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:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m 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@187572d1: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/jenkins14745648370965389386.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@187572d1: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-pcu-test-sns-asan #425

1 1

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

23 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 1

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

23 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 1

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

23 Dec '25

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

1 1

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

23 Dec '25

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

1 1

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

23 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 1

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

23 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 1

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

23 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 1

Build failed in Jenkins: ttcn3-upf-test-latest #116
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-upf-test-latest/116/display/r…> Changes: ------------------------------------------ Started by timer Running as SYSTEM ERROR: Issue with creating launcher for agent build5-deb12build-ansible. The agent has not been fully initialized yet 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)ERROR: build5-deb12build-ansible seems to be offline ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-upf-test-latest #116

1 1

Build failed in Jenkins: ttcn3-s1gw-test-latest #440
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-s1gw-test-latest/440/display/…> Changes: ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build4-deb12build-ansible (registry-build-amd64 ttcn3 obs osmo-gsm-tester-build ttcn3_with_linux_6.1_or_higher qemu io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-s1gw-test-latest 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 build4-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 ‘Publish JUnit test result report’ failed: no workspace for ttcn3-s1gw-test-latest #440

1 1

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications