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