Unstable Radio Link

[ali-iitd]

I have deployed the Dockerized VoLTE Setup. I am using an N USRP-2901, with an external clock Leo Bodnar and n_prb = 25.
The mme log shows UE getting connected then disconnected frequently.
In enb.conf
[rf]
tx_gain = 80
rx_gain = 40

I see alot of guys posting with similar issue however unable to find the solution.

[ianstorrs-cd]

I had a similar issue.
I enabled debug level logging in the enb for these layers:
[log]
all_level = error
s1ap_level = debug
rrc_level = debug
gtpu_level = debug
phy_level = error
mac_level = error
rlc_level = debug
pdcp_level = debug

The enb.log showed almost total crc errors on the downlink e.g.
2025-08-05T13:54:33.689839 [RRC ] [D] Radio-Link downlink state for rnti=0x47: crc_res=0, consecutive_ko=154
2025-08-05T13:54:33.689840 [RRC ] [D] Radio-Link uplink state for rnti=0x47: crc_res=0, consecutive_ko=155
2025-08-05T13:54:33.707838 [RRC ] [D] Radio-Link uplink state for rnti=0x47: crc_res=0, consecutive_ko=156
2025-08-05T13:54:33.708960 [RRC ] [D] Radio-Link uplink state for rnti=0x47: crc_res=0, consecutive_ko=157

It showed that SRB's and DRB's were having constant retransmissions, reconfigurations, radio link failures and UE releases.

So, I added a 10MHz external clock to the B210 and lowered the tx gain from 80 to 70. These changes eliminated the downlink CRC prints, and RLC traces showed downlink data was clean.

Still do not have internet connectivity, but the UE now remains connected with no L1/L2 radio issues.

[ianstorrs-cd]

Just a follow up, I now have full connectivity to the internet from the UE. The issue was that as well as iptable rules for NAT, you also need iptable rules for forwarding outbound IP from the srs_spgw-sgi interface to the internet gateway interface and rules to forward downlink IP traffic from the internet gateway interface back to the srs-spgw-sgi interface.

For my gateway interface wip0s20f3, I did these commands.

sudo iptables -t nat -A POSTROUTING -i srs_spgw_sgi -o wlp0s20f3 -j MASQUERADE
sudo iptables -A FORWARD -i srs_spgw_sgi -o wlp0s20f3 -j ACCEPT
sudo iptables -A FORWARD -i wlp0s20f3 -o srs_spgw_sgi -m conntrack --ctstate RELATED,ESTABLISHED
sudo netfilter-persistent save

All credit to Grok and Gemini for helping to resolve this.

[ianstorrs-cd]

Well, it turns out that adding the clock and lowering the output power was not a permanent solution. Whenever the UE restarts or the cell is lost, this instability during RACH occurs. Magically, after minutes or hours, the COTS UE is able to RACH, ATTACH, PDP Activate and maintain a good SRB and DRB without CRC errors. There seems to be a timing or synchronization issue during RACH and SRB setup.

lte    1  b8d    5   0    3    656    4   56  93% |   0.2    2.9   34    3    416    4  176  97%    0.0
RACH:  tti=3491, cc=0, pci=1, preamble=10, offset=9, temp_crnti=0xba0
Disconnecting rnti=0xba0.
RACH:  tti=3541, cc=0, pci=1, preamble=39, offset=9, temp_crnti=0xba1
Disconnecting rnti=0xba1.
RACH:  tti=3701, cc=0, pci=1, preamble=21, offset=9, temp_crnti=0xba2
Disconnecting rnti=0xba2.
RACH:  tti=3791, cc=0, pci=1, preamble=9, offset=9, temp_crnti=0xba3
Disconnecting rnti=0xba3.
RACH:  tti=3861, cc=0, pci=1, preamble=47, offset=9, temp_crnti=0xba4
Disconnecting rnti=0xba4.
RACH:  tti=3991, cc=0, pci=1, preamble=40, offset=9, temp_crnti=0xba5
Disconnecting rnti=0xba5.
RACH:  tti=4091, cc=0, pci=1, preamble=27, offset=9, temp_crnti=0xba6
Disconnecting rnti=0xba6.
lte    1  b8d  n/a   0    7      0    0   49 100% |   0.1    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d  n/a   0    9      0    0   16 100% |  -0.0    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d  n/a   0    0      0    0    0   0% |  -0.1    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d  n/a   0    9      0    0   16 100% |  -0.1    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d  n/a   0    0      0    0    0   0% |  -0.1    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d  n/a   0    0      0    0    0   0% |  -0.0    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d    9   0    7      0    0   39 100% |  -0.1   -0.0    0    4      0    0  125 100%    0.0
lte    1  b8d    9   0    4      0    0   39 100% |  -0.1    1.3    0    4      0    0  125 100%    0.0

               -----------------DL----------------|-------------------------UL-------------------------
rat  pci rnti  cqi  ri  mcs  brate   ok  nok  (%) | pusch  pucch  phr  mcs  brate   ok  nok  (%)    bsr
lte    1  b8d  n/a   0    2      0    0   26 100% |  -0.0    n/a    0    4      0    0  125 100%    0.0
lte    1  b8d  n/a   0    3      0    0   24 100% |  -0.0    n/a    0    4      0    0  125 100%    0.0
RACH:  tti=3931, cc=0, pci=1, preamble=41, offset=9, temp_crnti=0xba7
lte    1  b8d  n/a   0    0      0    0    0   0% |  -0.1    n/a    0    4      0    0  125 100%    0.0
lte    1  ba7   15   0    3      0    0    7 100% |  10.7   -2.0    0    0    223    1    0   0%    0.0
lte    1  b8d  n/a   0    2      0    0    7 100% |  -0.0    n/a    0    4      0    0  125 100%    0.0
lte    1  ba7  n/a   0    0      0    0    0   0% |   n/a    n/a    0    0      0    0    0   0%    0.0
RACH:  tti=5251, cc=0, pci=1, preamble=12, offset=9, temp_crnti=0xba8
Disconnecting rnti=0xba8.
RACH:  tti=5381, cc=0, pci=1, preamble=29, offset=9, temp_crnti=0xba9
Disconnecting rnti=0xba9.
RACH:  tti=5511, cc=0, pci=1, preamble=17, offset=9, temp_crnti=0xbaa
Disconnecting rnti=0xbaa.
RACH:  tti=5641, cc=0, pci=1, preamble=26, offset=9, temp_crnti=0xbab
Disconnecting rnti=0xbab.
RACH:  tti=5771, cc=0, pci=1, preamble=10, offset=9, temp_crnti=0xbac
Disconnecting rnti=0xbac.
RACH:  tti=5901, cc=0, pci=1, preamble=3, offset=9, temp_crnti=0xbad
Disconnecting rnti=0xbad.
Disconnecting rnti=0xba7.
RACH:  tti=6081, cc=0, pci=1, preamble=16, offset=9, temp_crnti=0xbae
Disconnecting rnti=0xbae.
RACH:  tti=6171, cc=0, pci=1, preamble=47, offset=9, temp_crnti=0xbaf
lte    1  b8d  n/a   0    0      0    0    0   0% |  -0.2    n/a    0    1      0    0  125 100%    0.0
lte    1  baf  n/a   0    0      0    0    0   0% |  21.7    n/a   15    0   5.1k    1    0   0%    111
Disconnecting rnti=0xbaf.
RACH:  tti=6261, cc=0, pci=1, preamble=30, offset=9, temp_crnti=0xbb0
Disconnecting rnti=0xbb0.
RACH:  tti=6351, cc=0, pci=1, preamble=40, offset=9, temp_crnti=0xb

2025-08-08T13:32:19.113895 [RRC    ] [D] Activity registered for rnti=0xba6 (timeout_value=80ms)
2025-08-08T13:32:19.113896 [RRC    ] [I] Setting RLF timer for rnti=0xba6 to 3000ms
2025-08-08T13:32:19.113903 [RLC    ] [I] Added LTE radio bearer with LCID 0 in Transparent Mode
2025-08-08T13:32:19.113906 [RRC    ] [I] Added new user rnti=0xba6
2025-08-08T13:32:19.116617 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=67
2025-08-08T13:32:19.118792 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=8
2025-08-08T13:32:19.124594 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=68
2025-08-08T13:32:19.124808 [RLC    ] [W] LCID 1 doesn't exist. Dropping PDU.
2025-08-08T13:32:19.124814 [RRC    ] [I] Resuming rnti=0xb8d RRC connection due to received C-RNTI CE from rnti=0xba6.
2025-08-08T13:32:19.125647 [RRC    ] [D] Radio-Link uplink state for rnti=0xba6: crc_res=1, consecutive_ko=0
2025-08-08T13:32:19.125648 [RRC    ] [D] Radio-Link uplink state for rnti=0xba6: crc_res=1, consecutive_ko=0
2025-08-08T13:32:19.125651 [GTPU   ] [I] Removing user - rnti=0xba6 not found.
2025-08-08T13:32:19.125673 [RRC    ] [I] Removed user rnti=0xba6
2025-08-08T13:32:19.126714 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=9
2025-08-08T13:32:19.136433 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=10
2025-08-08T13:32:19.142675 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=11
2025-08-08T13:32:19.150676 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=12
2025-08-08T13:32:19.158601 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=13
2025-08-08T13:32:19.166801 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=14
2025-08-08T13:32:19.174779 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=15
2025-08-08T13:32:19.182751 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=16
2025-08-08T13:32:19.190716 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=17
2025-08-08T13:32:19.191753 [RLC    ] [D] SRB1: Poll retx timer expired after 100ms
2025-08-08T13:32:19.191754 [RLC    ] [I] SRB1: Schedule SN=0 for retx
2025-08-08T13:32:19.191755 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.191756 [RLC    ] [D] SRB1: Buffer state - retx - SN=0, Segment: false, 0:44
2025-08-08T13:32:19.191756 [RLC    ] [D] SRB1: Buffer state - retx: 46 bytes
2025-08-08T13:32:19.191757 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 46 prio bytes
2025-08-08T13:32:19.191758 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=46
2025-08-08T13:32:19.191901 [RLC    ] [D] SRB1: MAC opportunity - 55 bytes
2025-08-08T13:32:19.191902 [RLC    ] [D] SRB1: tx_window size - 1 PDUs
2025-08-08T13:32:19.191903 [RLC    ] [I] SRB1: pdu_without_poll: 1
2025-08-08T13:32:19.191903 [RLC    ] [I] SRB1: byte_without_poll: 46
2025-08-08T13:32:19.191904 [RLC    ] [D] SRB1: Poll required. Cause: t-PollRetransmission expired.
2025-08-08T13:32:19.191904 [RLC    ] [D] SRB1: re-arming retx timer
2025-08-08T13:32:19.191905 [RLC    ] [I] SRB1: Tx PDU SN=0 (44 B) (attempt 22/32)
    0000: a0 00 00 08 01 20 3a 90 01 af b7 aa 0b c0 96 e4
    0010: 76 50 48 bf 38 2a 01 ec e8 80 6f 58 c4 25 01 a4
    0020: 00 03 1b 41 c4 f1 60 f0 6a e0 00 00
2025-08-08T13:32:19.191907 [RLC    ] [D] Tx PDU - SRB1: [Data PDU, RF=0, P=1, FI=0, SN=0, LSF=0, SO=0, N_li=0]
2025-08-08T13:32:19.191907 [RLC    ] [D] SRB1: vt_a = 0, vt_ms = 512, vt_s = 1, poll_sn = 0
2025-08-08T13:32:19.191908 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.191908 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 0 prio bytes
2025-08-08T13:32:19.191909 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=0
2025-08-08T13:32:19.198592 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=18
2025-08-08T13:32:19.200768 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=69
2025-08-08T13:32:19.206768 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=19
2025-08-08T13:32:19.208738 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=70
2025-08-08T13:32:19.214746 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=20
2025-08-08T13:32:19.216712 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=71
2025-08-08T13:32:19.222712 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=21
2025-08-08T13:32:19.224693 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=72
2025-08-08T13:32:19.230688 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=22
2025-08-08T13:32:19.238654 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=23
2025-08-08T13:32:19.246624 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=24
2025-08-08T13:32:19.254793 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=25
2025-08-08T13:32:19.262761 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=26
2025-08-08T13:32:19.270721 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=27
2025-08-08T13:32:19.278687 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=28
2025-08-08T13:32:19.286646 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=29
2025-08-08T13:32:19.291822 [RLC    ] [D] SRB1: Poll retx timer expired after 100ms
2025-08-08T13:32:19.291823 [RLC    ] [I] SRB1: Schedule SN=0 for retx
2025-08-08T13:32:19.291825 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.291825 [RLC    ] [D] SRB1: Buffer state - retx - SN=0, Segment: false, 0:44
2025-08-08T13:32:19.291826 [RLC    ] [D] SRB1: Buffer state - retx: 46 bytes
2025-08-08T13:32:19.291826 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 46 prio bytes
2025-08-08T13:32:19.291827 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=46
2025-08-08T13:32:19.292695 [RLC    ] [D] SRB1: MAC opportunity - 55 bytes
2025-08-08T13:32:19.292695 [RLC    ] [D] SRB1: tx_window size - 1 PDUs
2025-08-08T13:32:19.292696 [RLC    ] [I] SRB1: pdu_without_poll: 1
2025-08-08T13:32:19.292696 [RLC    ] [I] SRB1: byte_without_poll: 46
2025-08-08T13:32:19.292697 [RLC    ] [D] SRB1: Poll required. Cause: t-PollRetransmission expired.
2025-08-08T13:32:19.292697 [RLC    ] [D] SRB1: re-arming retx timer
2025-08-08T13:32:19.292698 [RLC    ] [I] SRB1: Tx PDU SN=0 (44 B) (attempt 23/32)
    0000: a0 00 00 08 01 20 3a 90 01 af b7 aa 0b c0 96 e4
    0010: 76 50 48 bf 38 2a 01 ec e8 80 6f 58 c4 25 01 a4
    0020: 00 03 1b 41 c4 f1 60 f0 6a e0 00 00
2025-08-08T13:32:19.292699 [RLC    ] [D] Tx PDU - SRB1: [Data PDU, RF=0, P=1, FI=0, SN=0, LSF=0, SO=0, N_li=0]
2025-08-08T13:32:19.292700 [RLC    ] [D] SRB1: vt_a = 0, vt_ms = 512, vt_s = 1, poll_sn = 0
2025-08-08T13:32:19.292701 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.292701 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 0 prio bytes
2025-08-08T13:32:19.292701 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=0
2025-08-08T13:32:19.294624 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=30
2025-08-08T13:32:19.301759 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=73
2025-08-08T13:32:19.302797 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=31
2025-08-08T13:32:19.308804 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=74
2025-08-08T13:32:19.310769 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=32
2025-08-08T13:32:19.316772 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=75
2025-08-08T13:32:19.318737 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=33
2025-08-08T13:32:19.325800 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=76
2025-08-08T13:32:19.326628 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=34
2025-08-08T13:32:19.334598 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=35
2025-08-08T13:32:19.342778 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=36
2025-08-08T13:32:19.350743 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=37
2025-08-08T13:32:19.358724 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=38
2025-08-08T13:32:19.366703 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=39
2025-08-08T13:32:19.374681 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=40
2025-08-08T13:32:19.382656 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=41
2025-08-08T13:32:19.390753 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=42
2025-08-08T13:32:19.392721 [RLC    ] [D] SRB1: Poll retx timer expired after 100ms
2025-08-08T13:32:19.392722 [RLC    ] [I] SRB1: Schedule SN=0 for retx
2025-08-08T13:32:19.392723 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.392724 [RLC    ] [D] SRB1: Buffer state - retx - SN=0, Segment: false, 0:44
2025-08-08T13:32:19.392725 [RLC    ] [D] SRB1: Buffer state - retx: 46 bytes
2025-08-08T13:32:19.392725 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 46 prio bytes
2025-08-08T13:32:19.392726 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=46
2025-08-08T13:32:19.392810 [RLC    ] [D] SRB1: MAC opportunity - 55 bytes
2025-08-08T13:32:19.392811 [RLC    ] [D] SRB1: tx_window size - 1 PDUs
2025-08-08T13:32:19.392812 [RLC    ] [I] SRB1: pdu_without_poll: 1
2025-08-08T13:32:19.392812 [RLC    ] [I] SRB1: byte_without_poll: 46
2025-08-08T13:32:19.392812 [RLC    ] [D] SRB1: Poll required. Cause: t-PollRetransmission expired.
2025-08-08T13:32:19.392813 [RLC    ] [D] SRB1: re-arming retx timer
2025-08-08T13:32:19.392813 [RLC    ] [I] SRB1: Tx PDU SN=0 (44 B) (attempt 24/32)
    0000: a0 00 00 08 01 20 3a 90 01 af b7 aa 0b c0 96 e4
    0010: 76 50 48 bf 38 2a 01 ec e8 80 6f 58 c4 25 01 a4
    0020: 00 03 1b 41 c4 f1 60 f0 6a e0 00 00
2025-08-08T13:32:19.392815 [RLC    ] [D] Tx PDU - SRB1: [Data PDU, RF=0, P=1, FI=0, SN=0, LSF=0, SO=0, N_li=0]
2025-08-08T13:32:19.392815 [RLC    ] [D] SRB1: vt_a = 0, vt_ms = 512, vt_s = 1, poll_sn = 0
2025-08-08T13:32:19.392816 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.392817 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 0 prio bytes
2025-08-08T13:32:19.392817 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=0
2025-08-08T13:32:19.398734 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=43
2025-08-08T13:32:19.401737 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=77
2025-08-08T13:32:19.406709 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=44
2025-08-08T13:32:19.409712 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=78
2025-08-08T13:32:19.414676 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=45
2025-08-08T13:32:19.417677 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=79
2025-08-08T13:32:19.422646 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=46
2025-08-08T13:32:19.425651 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=80
2025-08-08T13:32:19.430622 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=47
2025-08-08T13:32:19.438809 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=48
2025-08-08T13:32:19.444925 [RLC    ] [D] lcid=0, rx_rate_mbps=0.00 (real=0.00), tx_rate_mbps=0.00 (real=0.00)
2025-08-08T13:32:19.444927 [RLC    ] [D] lcid=1, rx_rate_mbps=0.00 (real=0.00), tx_rate_mbps=0.00 (real=0.00)
2025-08-08T13:32:19.444929 [PDCP   ] [D] lcid=1, rx_rate_mbps=0.00 (real=0.00), tx_rate_mbps=0.00 (real=0.00)
2025-08-08T13:32:19.446699 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=49
2025-08-08T13:32:19.454782 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=50
2025-08-08T13:32:19.462651 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=51
2025-08-08T13:32:19.470627 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=52
2025-08-08T13:32:19.478824 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=53
2025-08-08T13:32:19.486725 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=54
2025-08-08T13:32:19.492742 [RLC    ] [D] SRB1: Poll retx timer expired after 100ms
2025-08-08T13:32:19.492743 [RLC    ] [I] SRB1: Schedule SN=0 for retx
2025-08-08T13:32:19.492745 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.492745 [RLC    ] [D] SRB1: Buffer state - retx - SN=0, Segment: false, 0:44
2025-08-08T13:32:19.492746 [RLC    ] [D] SRB1: Buffer state - retx: 46 bytes
2025-08-08T13:32:19.492746 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 46 prio bytes
2025-08-08T13:32:19.492747 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=46
2025-08-08T13:32:19.492803 [RLC    ] [D] SRB1: MAC opportunity - 55 bytes
2025-08-08T13:32:19.492804 [RLC    ] [D] SRB1: tx_window size - 1 PDUs
2025-08-08T13:32:19.492805 [RLC    ] [I] SRB1: pdu_without_poll: 1
2025-08-08T13:32:19.492805 [RLC    ] [I] SRB1: byte_without_poll: 46
2025-08-08T13:32:19.492806 [RLC    ] [D] SRB1: Poll required. Cause: t-PollRetransmission expired.
2025-08-08T13:32:19.492806 [RLC    ] [D] SRB1: re-arming retx timer
2025-08-08T13:32:19.492807 [RLC    ] [I] SRB1: Tx PDU SN=0 (44 B) (attempt 25/32)
    0000: a0 00 00 08 01 20 3a 90 01 af b7 aa 0b c0 96 e4
    0010: 76 50 48 bf 38 2a 01 ec e8 80 6f 58 c4 25 01 a4
    0020: 00 03 1b 41 c4 f1 60 f0 6a e0 00 00
2025-08-08T13:32:19.492808 [RLC    ] [D] Tx PDU - SRB1: [Data PDU, RF=0, P=1, FI=0, SN=0, LSF=0, SO=0, N_li=0]
2025-08-08T13:32:19.492809 [RLC    ] [D] SRB1: vt_a = 0, vt_ms = 512, vt_s = 1, poll_sn = 0
2025-08-08T13:32:19.492810 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.492811 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 0 prio bytes
2025-08-08T13:32:19.492811 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=0
2025-08-08T13:32:19.494729 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=55
2025-08-08T13:32:19.501669 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=81
2025-08-08T13:32:19.502706 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=56
2025-08-08T13:32:19.509737 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=82
2025-08-08T13:32:19.510670 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=57
2025-08-08T13:32:19.517731 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=83
2025-08-08T13:32:19.518663 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=58
2025-08-08T13:32:19.524680 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=84
2025-08-08T13:32:19.526753 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=59
2025-08-08T13:32:19.534747 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=60
2025-08-08T13:32:19.542714 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=61
2025-08-08T13:32:19.550682 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=62
2025-08-08T13:32:19.558647 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=63
2025-08-08T13:32:19.566624 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=64
2025-08-08T13:32:19.574811 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=65
2025-08-08T13:32:19.582792 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=66
2025-08-08T13:32:19.590762 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=67
2025-08-08T13:32:19.592635 [RLC    ] [D] SRB1: Poll retx timer expired after 100ms
2025-08-08T13:32:19.592635 [RLC    ] [I] SRB1: Schedule SN=0 for retx
2025-08-08T13:32:19.592636 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.592637 [RLC    ] [D] SRB1: Buffer state - retx - SN=0, Segment: false, 0:44
2025-08-08T13:32:19.592637 [RLC    ] [D] SRB1: Buffer state - retx: 46 bytes
2025-08-08T13:32:19.592638 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 46 prio bytes
2025-08-08T13:32:19.592639 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=46
2025-08-08T13:32:19.592684 [RLC    ] [D] SRB1: MAC opportunity - 55 bytes
2025-08-08T13:32:19.592684 [RLC    ] [D] SRB1: tx_window size - 1 PDUs
2025-08-08T13:32:19.592686 [RLC    ] [I] SRB1: pdu_without_poll: 1
2025-08-08T13:32:19.592686 [RLC    ] [I] SRB1: byte_without_poll: 46
2025-08-08T13:32:19.592686 [RLC    ] [D] SRB1: Poll required. Cause: t-PollRetransmission expired.
2025-08-08T13:32:19.592687 [RLC    ] [D] SRB1: re-arming retx timer
2025-08-08T13:32:19.592687 [RLC    ] [I] SRB1: Tx PDU SN=0 (44 B) (attempt 26/32)
    0000: a0 00 00 08 01 20 3a 90 01 af b7 aa 0b c0 96 e4
    0010: 76 50 48 bf 38 2a 01 ec e8 80 6f 58 c4 25 01 a4
    0020: 00 03 1b 41 c4 f1 60 f0 6a e0 00 00
2025-08-08T13:32:19.592689 [RLC    ] [D] Tx PDU - SRB1: [Data PDU, RF=0, P=1, FI=0, SN=0, LSF=0, SO=0, N_li=0]
2025-08-08T13:32:19.592689 [RLC    ] [D] SRB1: vt_a = 0, vt_ms = 512, vt_s = 1, poll_sn = 0
2025-08-08T13:32:19.592691 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.592691 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 0 prio bytes
2025-08-08T13:32:19.592691 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=0
2025-08-08T13:32:19.598651 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=68
2025-08-08T13:32:19.601652 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=85
2025-08-08T13:32:19.606625 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=69
2025-08-08T13:32:19.608798 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=86
2025-08-08T13:32:19.614800 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=70
2025-08-08T13:32:19.617593 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=87
2025-08-08T13:32:19.622764 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=71
2025-08-08T13:32:19.625766 [RRC    ] [D] Radio-Link downlink state for rnti=0xb8d: crc_res=0, consecutive_ko=88
2025-08-08T13:32:19.630737 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=72
2025-08-08T13:32:19.638703 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=73
2025-08-08T13:32:19.646678 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=74
2025-08-08T13:32:19.654643 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=75
2025-08-08T13:32:19.662821 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=76
2025-08-08T13:32:19.670708 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=77
2025-08-08T13:32:19.678777 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=78
2025-08-08T13:32:19.686651 [RRC    ] [D] Radio-Link uplink state for rnti=0xb8d: crc_res=0, consecutive_ko=79
2025-08-08T13:32:19.692774 [RLC    ] [D] SRB1: Poll retx timer expired after 100ms
2025-08-08T13:32:19.692776 [RLC    ] [I] SRB1: Schedule SN=0 for retx
2025-08-08T13:32:19.692778 [RLC    ] [D] SRB1: Buffer state - do_status=no, status_prohibit_running=no (0/0)
2025-08-08T13:32:19.692780 [RLC    ] [D] SRB1: Buffer state - retx - SN=0, Segment: false, 0:44
2025-08-08T13:32:19.692780 [RLC    ] [D] SRB1: Buffer state - retx: 46 bytes
2025-08-08T13:32:19.692781 [RLC    ] [D] SRB1: Calling BSR callback - 0 new_tx, 46 prio bytes
2025-08-08T13:32:19.692783 [RLC    ] [D] Buffer state: rnti=0xb8d, lcid=1, tx_queue=0, prio_tx_queue=46
2025-08-08T13:32:19.692831 [RLC    ] [D] SRB1: MAC opportunity - 55 bytes
2025-08-08T13:32:19.692831 [RLC    ] [D] SRB1: tx_window size - 1 PDUs