srsRAN_4G icon indicating copy to clipboard operation
srsRAN_4G copied to clipboard

5G SA COTS UE Disconnecting after a few minutes "Removed user rnti=0x4601"

Open cristina-posada opened this issue 2 years ago • 6 comments

Issue Description

Following the 5G SA COTS UE Application Note, after running srsENB with sudo ./srsenb enb.conf the UE successfully connects to the open5gs net and gets an assigned IP and access to the internet. But after a few seconds or minutes the UE disconnects from the network.

Setup Details

  • Operation System: Ubuntu 20.04
  • srsRAN (srsRAN-agpl_next)
  • RF: USRP B210 (UHD driver)
  • Asus ZenBook intel Core i7
  • UE: Galaxy S21 FE 5G Sysmocom USIM
  • Third Party 5G Core: Open5GS

Expected Behavior

The UE should be connected to the enb indefinitely

Actual Behaviour

After a few seconds or minutes the UE disconnects from the network, the ping from PC to UE stop having a reply and the UE stops having acces to the internet.

srsenb Output:

==== eNodeB started ===
Type <t> to view trace
Setting manual TX/RX offset to 100 samples
Setting frequency: DL=1847.5 Mhz, UL=1752.5 MHz for cc_idx=0 nof_prb=52
RACH:  slot=6251, cc=0, preamble=1, offset=3, temp_crnti=0x4601
RACH:  slot=5291, cc=0, preamble=2, offset=1477, temp_crnti=0x4602
Disconnecting rnti=0x4602.
RACH:  slot=5451, cc=0, preamble=6, offset=1477, temp_crnti=0x4603
Disconnecting rnti=0x4603.
RACH:  slot=5611, cc=0, preamble=4, offset=1477, temp_crnti=0x4604
Disconnecting rnti=0x4604.
RACH:  slot=5771, cc=0, preamble=1, offset=1477, temp_crnti=0x4605
Disconnecting rnti=0x4605.
RACH:  slot=5931, cc=0, preamble=7, offset=1477, temp_crnti=0x4606
Disconnecting rnti=0x4606.
RACH:  slot=6091, cc=0, preamble=4, offset=1477, temp_crnti=0x4607
Disconnecting rnti=0x4607.
RACH:  slot=6251, cc=0, preamble=2, offset=1475, temp_crnti=0x4608
Disconnecting rnti=0x4608.
RACH:  slot=6731, cc=0, preamble=6, offset=3, temp_crnti=0x4609
User 0x4609 requesting RRC Reestablishment as 0x4601. Cause: otherFailure
Disconnecting rnti=0x4601.
RACH:  slot=6891, cc=0, preamble=3, offset=3, temp_crnti=0x460a
Disconnecting rnti=0x460a.
RACH:  slot=7371, cc=0, preamble=4, offset=3, temp_crnti=0x460b
User 0x460b requesting RRC Reestablishment as 0x4609. Cause: reconfigurationFailure
Disconnecting rnti=0x4609.
RACH:  slot=7531, cc=0, preamble=4, offset=3, temp_crnti=0x460c
Disconnecting rnti=0x460c.
RACH:  slot=7691, cc=0, preamble=1, offset=3, temp_crnti=0x460d
Disconnecting rnti=0x460d.
RACH:  slot=7851, cc=0, preamble=6, offset=3, temp_crnti=0x460e
Disconnecting rnti=0x460e.
RACH:  slot=8011, cc=0, preamble=2, offset=5, temp_crnti=0x460f
Disconnecting rnti=0x460f.
RACH:  slot=8171, cc=0, preamble=2, offset=3, temp_crnti=0x4610
Disconnecting rnti=0x4610.
RACH:  slot=8331, cc=0, preamble=2, offset=3, temp_crnti=0x4611
Disconnecting rnti=0x4611.
RACH:  slot=8491, cc=0, preamble=0, offset=3, temp_crnti=0x4612
Disconnecting rnti=0x4612.
RACH:  slot=8651, cc=0, preamble=1, offset=3, temp_crnti=0x4613
Disconnecting rnti=0x4613.
RACH:  slot=9131, cc=0, preamble=5, offset=5, temp_crnti=0x4614
User 0x4614 requesting RRC Reestablishment as 0x460b. Cause: otherFailure
Disconnecting rnti=0x460b.
RACH:  slot=9291, cc=0, preamble=3, offset=3, temp_crnti=0x4615
Disconnecting rnti=0x4615.
RACH:  slot=9451, cc=0, preamble=4, offset=3, temp_crnti=0x4616
Disconnecting rnti=0x4616.
RACH:  slot=9611, cc=0, preamble=4, offset=3, temp_crnti=0x4617
Disconnecting rnti=0x4617.
RACH:  slot=9771, cc=0, preamble=2, offset=3, temp_crnti=0x4618
Disconnecting rnti=0x4618.
RACH:  slot=9931, cc=0, preamble=2, offset=3, temp_crnti=0x4619
Disconnecting rnti=0x4619.
RACH:  slot=10091, cc=0, preamble=5, offset=3, temp_crnti=0x461a
Disconnecting rnti=0x461a.
RACH:  slot=11, cc=0, preamble=7, offset=3, temp_crnti=0x461b
Disconnecting rnti=0x461b.
RACH:  slot=171, cc=0, preamble=4, offset=3, temp_crnti=0x461c
Disconnecting rnti=0x461c.
RACH:  slot=491, cc=0, preamble=5, offset=3, temp_crnti=0x461d
User 0x461d requesting RRC Reestablishment as 0x4614. Cause: otherFailure
Disconnecting rnti=0x4614.
RACH:  slot=651, cc=0, preamble=4, offset=3, temp_crnti=0x461e
Disconnecting rnti=0x461e.
RACH:  slot=811, cc=0, preamble=1, offset=3, temp_crnti=0x461f
Disconnecting rnti=0x461f.
RACH:  slot=971, cc=0, preamble=7, offset=3, temp_crnti=0x4620
Disconnecting rnti=0x4620.
RACH:  slot=1131, cc=0, preamble=1, offset=3, temp_crnti=0x4621
Disconnecting rnti=0x4621.
RACH:  slot=1291, cc=0, preamble=1, offset=3, temp_crnti=0x4622
Disconnecting rnti=0x4622.
RACH:  slot=1451, cc=0, preamble=5, offset=3, temp_crnti=0x4623
Disconnecting rnti=0x4623.
RACH:  slot=1611, cc=0, preamble=3, offset=3, temp_crnti=0x4624
Disconnecting rnti=0x4624.
RACH:  slot=1771, cc=0, preamble=0, offset=3, temp_crnti=0x4625
Disconnecting rnti=0x4625.
RACH:  slot=2091, cc=0, preamble=6, offset=3, temp_crnti=0x4626
User 0x4626 requesting RRC Reestablishment as 0x461d. Cause: otherFailure
Disconnecting rnti=0x461d.
RACH:  slot=2251, cc=0, preamble=4, offset=3, temp_crnti=0x4627
Disconnecting rnti=0x4627.
RACH:  slot=2411, cc=0, preamble=7, offset=3, temp_crnti=0x4628
Disconnecting rnti=0x4628.
RACH:  slot=2571, cc=0, preamble=1, offset=3, temp_crnti=0x4629
Disconnecting rnti=0x4629.
RACH:  slot=2731, cc=0, preamble=4, offset=5, temp_crnti=0x462a
Disconnecting rnti=0x462a.
RACH:  slot=2891, cc=0, preamble=3, offset=5, temp_crnti=0x462b
Disconnecting rnti=0x462b.
RACH:  slot=3051, cc=0, preamble=1, offset=5, temp_crnti=0x462c
Disconnecting rnti=0x462c.
RACH:  slot=3211, cc=0, preamble=5, offset=5, temp_crnti=0x462d
Disconnecting rnti=0x462d.
RACH:  slot=3371, cc=0, preamble=3, offset=5, temp_crnti=0x462e
Disconnecting rnti=0x462e.

After failure, If I activate airplane mode in the UE and deactivate it again, the UE reconnects without any problems with the network and stays connected for a few minutes with another IP, until it disconnects again and so on.

Sometimes it is connected for 20 minutes and others for just a few seconds...

I use a large RRC inactivity timer in the enb.conf rrc_inactivity_timer = 1073741823

Steps to reproduce the problem

To reproduce this issue follow the guide https://docs.srsran.com/projects/4g/en/latest/app_notes/source/5g_sa_COTS/source/index.html Copy the config files, execute srsenb sudo ./srsenb enb.conf and try to connect the UE to the open5GS net.

srsRAN Config files: enb.conf.txt rr.conf.txt rb.conf.txt sib.conf.txt

Open5GS config files: amf.yaml.txt upf.yaml.txt

Additional Information

enb.log at the moment of disconnection (When the srsenb output shows RACH: slot=5291, cc=0, preamble=2, offset=1477, temp_crnti=0x4602):

2022-06-30T11:14:54.711416 [PHY1-NR] [I] [ 7676] PDSCH: cc=0 si-rnti=0xffff prb=(1,8) symb=(2,13) CW0: mod=QPSK tbs=80 R=0.380 rv=0  tti_tx=7680
2022-06-30T11:14:54.716369 [PHY0-NR] [I] [ 7681] PUCCH: f=2 prb=50:1 symb=2:2 rnti=0x4601 cqi=0 epre=+10.0 snr=-5.6 cfo=+5448.8 delay=-3.9 valid=y 
2022-06-30T11:14:54.723472 [PHY1-NR] [I] [ 7688] PUCCH: f=1 prb=51:1 symb=0:14 cs=8 occ=0 rnti=0x4601 sr=1 epre=+8.2 snr=-10.9 cfo=-614.0 delay=-2.1 valid=n 
2022-06-30T11:14:54.726484 [PHY-NR ] [I] [    0] PRACH: cc=0, 0/1, preamble=5, offset=1.9 us, peak2avg=401.2, max_offset=1000.0 us
2022-06-30T11:14:54.726614 [MAC-NR ] [I] [ 7691] SCHED: rnti=0x4602, new lcid configuration: [{lcid=0, mode=bi-dir, prio=1, lcg=0}]
2022-06-30T11:14:54.726677 [RLC-NR ] [I] Added LTE radio bearer with LCID 0 in Transparent Mode
2022-06-30T11:14:54.726682 [RRC-NR ] [I] Added new user rnti=0x4602
2022-06-30T11:14:54.726682 [MAC-NR ] [I] [ 7691] RACH:  slot=7691, cc=0, preamble=5, offset=3, temp_crnti=0x4602
2022-06-30T11:14:54.726690 [MAC-NR ] [I] [ 7691] rach_tprof: {mean, max, min}={192.1, 204, 179} usec, nof_samples=2
2022-06-30T11:14:54.727302 [MAC-NR ] [I] [ 7692] SCHED: New user rnti=0x4602, cc=0
2022-06-30T11:14:54.727303 [MAC-NR ] [I] [ 7692] SCHED: New PRACH slot=7691, preamble=5, ra-rnti=0xf, temp_crnti=0x4602, ta_cmd=3, msg3_size=7
2022-06-30T11:14:54.727336 [MAC-NR ] [I] [ 7692] SCHED: RAR, cc=0, ra-rnti=0xf, prbs=[1, 5), pdsch_slot=7696, msg3_slot=7702, nof_grants=1
2022-06-30T11:14:54.727342 [MAC-NR ] [I] [ 7692] Setting RAR Grant tc-rnti=0x4602 dci=RAR ss=rar hop=0 f_alloc=0x68 t_alloc=0x0 mcs=0 tpc=1 csi=0 
2022-06-30T11:14:54.727345 [MAC-NR ] [I] [ 7692] DL RAPID: 5, Temp C-RNTI: 0x4602, TA: 3, UL Grant: [00 d0 00 40 ]
2022-06-30T11:14:54.727378 [PHY1-NR] [I] [ 7692] PDCCH: cc=0 ra-rnti=0x000f dci=1_0 ss=common1 L=2 cce=0 f_alloc=0x90 t_alloc=0x0 vrb_to_prb_map=0 mcs=5 tb_scaling=0 reserved=0x0  tti_tx=7696
2022-06-30T11:14:54.727409 [PHY1-NR] [I] [ 7692] PDSCH: cc=0 ra-rnti=0xf prb=(1,4) symb=(1,13) CW0: mod=QPSK tbs=44 R=0.383 rv=0  tti_tx=7696
2022-06-30T11:14:54.731433 [PHY1-NR] [I] [ 7696] PDCCH: cc=0 si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 f_alloc=0x150 t_alloc=0x0 vrb_to_prb_map=0 mcs=5 rv=0 sii=0 coreset0_bw=48 reserved=0x0  tti_tx=7700
2022-06-30T11:14:54.731472 [PHY1-NR] [I] [ 7696] PDSCH: cc=0 si-rnti=0xffff prb=(1,8) symb=(2,13) CW0: mod=QPSK tbs=80 R=0.380 rv=0  tti_tx=7700
2022-06-30T11:14:54.737508 [PHY1-NR] [I] [ 7702] PUSCH: tc-rnti=0x4602 prb=(0,2) symb=(0,13) CW0: mod=QPSK tbs=11 R=0.131 rv=0 CRC=OK iter=1.0 evm=0.28 t_us=29 epre=+41.5 snr=+11.7 cfo=-6.8 delay=+0.0 
2022-06-30T11:14:54.737555 [MAC-NR ] [I] [ 7702] Rx PDU: rnti=0x4602, UL CCCH48: len=6 LBSR: bitmap=0x0 PAD: len=0
2022-06-30T11:14:54.737565 [RRC-NR ] [I] rnti=0x4602, SRB0 - Rx UL-CCCH.rrcReestablishmentRequest (6 B)

The new user "Added new user rnti=0x4602" is always added after a PRACH

On another try I see the message Removed user rnti=0x4601 at enb.log

2022-06-30T12:03:52.476777 [GTPU   ] [I] Modifying bearer rnti. Old rnti: 0x4601, new rnti: 0x4602
2022-06-30T12:03:52.476795 [STCK   ] [I] Bearers: Removed rnti=0x4601 from EPS bearer manager
2022-06-30T12:03:52.476908 [RRC-NR ] [I] Removed user rnti=0x4601
2022-06-30T12:03:52.477528 [MAC-NR ] [I] [ 7223] SCHED: rnti=0x4602, new lcid configuration: [{lcid=1, mode=bi-dir, prio=1, lcg=0}]
2022-06-30T12:03:52.478367 [MAC-NR ] [I] [ 7223] SCHED: Removed user rnti=0x4601
2022-06-30T12:03:52.478386 [MAC-NR ] [I] [ 7223] SCHED: DL tx, cc=0, rnti=0x4602, pid=0, cs=0, f=1_0, prbs=[1, 49), nrtx=0, dai=0, lcids=[62, 1], tbs=960, bs=16, pdsch_slot=7227, ack_slot=7231
2022-06-30T12:03:52.478393 [RLC-NR ] [I] SRB1: Started t-PollRetransmit. POLL_SN=0
2022-06-30T12:03:52.478394 [RLC-NR ] [I] SRB1: [Data PDU, P=1, SI=full, SN_SIZE=12 bits, SN=0, SO=0]
2022-06-30T12:03:52.478396 [RLC-NR ] [I] SRB1: No data available to be sent
2022-06-30T12:03:52.478398 [MAC-NR ] [I] [ 7223] 0x4602 DL CON_RES: id=48c02fa56934 LCID=1 len=10 PAD: len=940
2022-06-30T12:03:52.478425 [PHY1-NR] [I] [ 7223] PDCCH: cc=0 c-rnti=0x4602 dci=1_0 ss=common3 L=2 cce=0 f_alloc=0x5f t_alloc=0x0 vrb_to_prb_map=0 mcs=10 ndi=1 rv=0 harq_id=0 dai=0 pucch_tpc=1 pucch_res=>
2022-06-30T12:03:52.478580 [PHY1-NR] [I] [ 7223] PDSCH: cc=0 c-rnti=0x4602 prb=(1,48) symb=(1,13) CW0: mod=16QAM tbs=960 R=0.334 rv=0  tti_tx=7227
2022-06-30T12:03:52.485601 [PHY1-NR] [I] [ 7231] PUCCH: f=1 prb=51:1 symb=0:14 cs=0 occ=0 rnti=0x4602 ack=1 epre=+43.6 snr=+13.4 cfo=-547.1 delay=+4.0 valid=y
2022-06-30T12:03:52.490462 [PHY0-NR] [I] [ 7236] PDCCH: cc=0 si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 f_alloc=0x150 t_alloc=0x0 vrb_to_prb_map=0 mcs=5 rv=0 sii=0 coreset0_bw=48 reserved=0x0  tti_tx=7>
2022-06-30T12:03:52.490490 [PHY0-NR] [I] [ 7236] PDSCH: cc=0 si-rnti=0xffff prb=(1,8) symb=(2,13) CW0: mod=QPSK tbs=80 R=0.380 rv=0  tti_tx=7240

cristina-posada avatar Jun 30 '22 10:06 cristina-posada

Hi @cristina-posada, did you manage to clarify this? Thanks

avalori1 avatar Jan 19 '23 08:01 avalori1

Hi @avalori1 No, sadly not yet. I have installed the latest versions of srsRAN and open5gs recently this week and the problem still exists for me.

cristina-posada avatar Mar 03 '23 16:03 cristina-posada

Hi @cristina-posada,

I'm trying to figure out why I'm having similar issues.

Was your Sysmocom SJA2 purchased before or after September 2021 (is it SJA2v1 or SJA2v2)?

Thank you

igoforth avatar Apr 26 '23 23:04 igoforth

Hi

Did you have the same issue with the new srsRAN repository https://github.com/srsran/srsRAN_Project? If so, can you open an issue there?

Thanks

frankist avatar Apr 27 '23 07:04 frankist

Hi @igoforth, @frankist. In my case, I don't have the same issue with the new repository https://github.com/srsran/srsRAN_Project. I tested it a few weeks ago and the UE was connected for hours.

I don't know when the card I'm using was purchased, I don't know if it's version SJA2v1 or SJA2v2 but I'll find out and let you know!

Thanks

cristina-posada avatar Apr 27 '23 08:04 cristina-posada

Hi

Did you have the same issue with the new srsRAN repository https://github.com/srsran/srsRAN_Project? If so, can you open an issue there?

Thanks

@frankist @cristina-posada Thank you for the response.

In the case of my UE with srsRAN Project, it disconnects after 2-5 minutes. I have a feeling that's because Project's gNB does not yet support RRC Mobility, Paging and Reestablishment. I'm looking for idle state transition capabilities.

Thanks

igoforth avatar Apr 27 '23 12:04 igoforth