Cellular Network Infrastructure » Radio Access Network » OsmoPCU

Mar  1 20:17:04 OsmoPCU: DTBFDL INFO tbf_dl.cpp:983 TBF(TFI=4 TLLI=0xca1cd311 DIR=DL STATE=FINISHED EGPRS) Scheduled Ack/Nack polling on FN=589866, TS=6    // WE SCHEDULE A POTENTIAL NEW FINAL ACK/NACK POLL
Mar  1 20:17:04 OsmoPCU: DTBF INFO tbf_dl.cpp:1146 TBF(DL-TFI_4)[0x815990]{FINISHED}: Received Event FINAL_ACK_RECVD   // FINAL ACK IS RECEIVED HERE! (from previous poll to the one above? is that possible?) maybe we don't mark the poll as done here?
Mar  1 20:17:04 OsmoPCU: DTBF INFO tbf_fsm.c:258 TBF(DL-TFI_4)[0x815990]{FINISHED}: state_chg to WAIT_RELEASE   // WE MOVE TO WAIT_RELEASE HERE
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=6) Timeout for registered USF (FN=589849): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=7) Timeout for registered USF (FN=589849): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=6) Timeout for registered USF (FN=589853): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=7) Timeout for registered USF (FN=589853): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=6) Timeout for registered USF (FN=589857): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=7) Timeout for registered USF (FN=589857): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=6) Timeout for registered USF (FN=589862): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC INFO pdch_ul_controller.c:323 PDCH(bts=0,trx=1,ts=7) Timeout for registered USF (FN=589862): TBF(TFI=3 TLLI=0xcd900511 DIR=UL STATE=FLOW EGPRS)
Mar  1 20:17:04 OsmoPCU: DRLCMAC NOTICE pdch_ul_controller.c:330 PDCH(bts=0,trx=1,ts=6) Timeout for registered POLL (FN=589866, reason=DL_ACK): TBF(TFI=4 TLLI=0xca1cd311 DIR=DL STATE=WAIT_RELEASE EGPRS) // POLL WE SCHEDULE IN FIRST LINE TIMES OUT HERE
Mar  1 20:17:04 OsmoPCU: DTBF NOTICE tbf.cpp:552 TBF(TFI=4 TLLI=0xca1cd311 DIR=DL STATE=WAIT_RELEASE EGPRS) poll timeout for FN=589866, TS=6 (curr FN 589866)
Mar  1 20:17:04 OsmoPCU: DTBF NOTICE tbf.cpp:630 TBF(TFI=4 TLLI=0xca1cd311 DIR=DL STATE=WAIT_RELEASE EGPRS) Timeout for polling PACKET DOWNLINK ACK: |Assignment was on PACCH|Downlink ACK was received|
Mar  1 20:17:04 OsmoPCU: DTBF INFO tbf.cpp:646 TBF(DL-TFI_4)[0x815990]{WAIT_RELEASE}: Received Event DL_ACKNACK_MISS
Mar  1 20:17:04 OsmoPCU: DTBF ERROR tbf.cpp:646 TBF(DL-TFI_4)[0x815990]{WAIT_RELEASE}: Event DL_ACKNACK_MISS not permitted //<---- WHAT TO DO WITH THIS?

There's 2 possibilities here, I need to investigate:

• 1 scheduled FINAL ACK poll in place, which even if receiving the final ack (FINAL_ACK_RECVD), it doesn't mark the poll as done, and later on it times out
• 2 scheduled FINAL ACK polls in place, first one finishes fine (FINAL_ACK_RECVD), then the second one times out

If it's the first option, then we need to fix the path and then the event shouldn't happen.
If it's the second option, then we need to accept the event and do nothing (ignore).