Hi,
I’m trying to duplicate the scenario presented in the latest version of srsRAN using 5g SA configuration. I followed the guidelines mentioned in this link https://docs.srsran.com/en/latest/app_notes/source/5g_sa_amari/source/index.html#g-sa-srsue
I’m using USRP N310 SDR board and I’m stuck in the UE Attach phase as can be seen in the image attached.
Part of the log file of the user equipment (ue.log) is given below:
2022-05-18T13:18:17.923117 [UE ] [I] Using binary srsue with arguments: .config/srsran/ue.conf
2022-05-18T13:18:17.949707 [UE ] [I] Built in Release mode using commit f2dff0b7a on branch master.
2022-05-18T13:18:17.988747 [RF ] [I] Configuring physical DL channel 0 with band-pass filter (0.0, 0.0)
2022-05-18T13:18:17.988747 [RF ] [I] Configuring physical UL channel 0 with band-pass filter (0.0, 0.0)
2022-05-18T13:18:41.072647 [PHY-SA ] [D] [ 0] SYNC: state=IDLE, tti=0
2022-05-18T13:18:41.072648 [PHY-SA ] [D] [ 0] Discarding samples and sending tx_end
2022-05-18T13:18:41.076859 [MAC ] [I] [ 0] Resetting MAC
2022-05-18T13:18:41.076862 [MAC ] [D] [ 0] Msg3 buffer flushed
2022-05-18T13:18:41.076864 [MAC ] [I] [ 0] Logical Channel Setup: LCID=0, LCG=0, priority=0, PBR=-1, BSD=50ms, bucket_size=0
2022-05-18T13:18:41.076867 [MAC ] [I] [ 0] Logical Channel Setup: LCID=1, LCG=0, priority=1, PBR=-1, BSD=50ms, bucket_size=0
2022-05-18T13:18:41.076878 [MAC ] [D] [ 0] After MAC reset:
- lcid=0, lcg=0, prio=0, Bj=0, PBR=-1, BSD=50, buffer_len=0, sched_len=0
- lcid=1, lcg=0, prio=1, Bj=0, PBR=-1, BSD=50, buffer_len=0, sched_len=0
2022-05-18T13:18:41.076879 [MAC ] [I] [ 0] Setting configuration
2022-05-18T13:18:41.076881 [MAC ] [I] [ 0] BSR: Configured timer reTX 2560 ms
2022-05-18T13:18:41.076919 [RLC ] [I] Added LTE radio bearer with LCID 0 in Transparent Mode
2022-05-18T13:18:41.076925 [USIM ] [I] Read Home PLMN Id=00101
2022-05-18T13:18:41.080391 [RLC-NR ] [I] Added LTE radio bearer with LCID 0 in Transparent Mode
2022-05-18T13:18:41.080394 [MAC-NR ] [D] [ 0] cc_idx=0, set number of HARQ processes for DL to 8
2022-05-18T13:18:41.080396 [MAC-NR ] [I] [ 0] Logical Channel Setup: LCID=0, LCG=0, priority=0, PBR=0, BSD=0ms, bucket_size=0
2022-05-18T13:18:41.080402 [RRC ] [I] using srand seed of 80402
2022-05-18T13:18:41.103272 [MAC ] [D] [ 0] DL retx: 0.00 %, perpkt: 0.00, UL retx: 0.00 % perpkt: 0.00
2022-05-18T13:18:41.103275 [RLC ] [D] lcid=0, rx_rate_mbps=0.00 (real=0.00), tx_rate_mbps=0.00 (real=0.00)
2022-05-18T13:18:41.103524 [GW ] [D] gw_rx_rate_mbps=0.00 (real=0.00), gw_tx_rate_mbps=0.00 (real=0.00)
2022-05-18T13:18:41.104760 [STCK ] [I] Triggering NAS switch on
2022-05-18T13:18:41.104816 [NAS5G ] [I] Switching on
2022-05-18T13:18:41.104820 [NAS5G ] [D] Changed to mm5g state: DEREGISTERED, with substate PLMN-SEARCH
2022-05-18T13:18:41.175325 [PHY-SA ] [D] [ 0] run_stack_tti: from recv
2022-05-18T13:18:41.175326 [PHY-SA ] [D] [ 0] SYNC: Setting initial TTI time to 0.145641
2022-05-18T13:18:41.175327 [PHY-SA ] [D] [ 0] run_stack_tti: calling stack tti=0, tti_jump=1
2022-05-18T13:18:41.175328 [PHY-SA ] [D] [ 0] run_stack_tti: stack called
2022-05-18T13:18:41.175328 [PHY-SA ] [D] [ 0] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.175330 [PHY-SA ] [D] [ 0] SYNC: state=IDLE, tti=0
2022-05-18T13:18:41.175331 [PHY-SA ] [D] [ 0] Discarding samples and sending tx_end
2022-05-18T13:18:41.175412 [MAC ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.175413 [MAC ] [D] [ 0] Update Bj: lcid=0, Bj=0
2022-05-18T13:18:41.175413 [MAC ] [D] [ 0] Update Bj: lcid=1, Bj=0
2022-05-18T13:18:41.175416 [MAC ] [D] [ 0] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.175417 [MAC-NR ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.175424 [MAC-NR ] [D] [ 0] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.175427 [RRC ] [D] State IDLE
2022-05-18T13:18:41.175457 [USIM ] [I] Read Home PLMN Id=00101
2022-05-18T13:18:41.175458 [NAS5G ] [I] Requesting IMSI attach (IMSI=001010123456789)
2022-05-18T13:18:41.175462 [NAS5G ] [D] Starting T3410. Timeout in 15000 ms.
2022-05-18T13:18:41.175463 [NAS5G ] [I] Sending Registration Request
2022-05-18T13:18:41.175463 [NAS5G ] [D] Initiating RRC NR Connection
2022-05-18T13:18:41.175464 [RRC-NR ] [I] Proc “Setup Request” - Initiation of Setup request procedure
2022-05-18T13:18:41.175465 [RRC-NR ] [I] Proc “Cell Selection” - Starting…
2022-05-18T13:18:41.175470 [NAS5G ] [D] Changed to mm5g state: REGISTERED-INITIATED
2022-05-18T13:18:41.175471 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:41.175473 [PHY-SA ] [I] [ 0] Cell Search: Going to IDLE
2022-05-18T13:18:41.175474 [PHY-SA ] [I] [ 0] Tuning Rx channel 0 to 1842.50 MHz
2022-05-18T13:18:41.175517 [RF ] [I] Mapping RF channel 0 (device=0, channel=0) to logical carrier 0 on f_rx=1842.5 MHz
2022-05-18T13:18:41.176362 [PHY-SA ] [D] [ 0] run_stack_tti: from recv
2022-05-18T13:18:41.176363 [PHY-SA ] [D] [ 0] run_stack_tti: calling stack tti=0, tti_jump=1
2022-05-18T13:18:41.176363 [PHY-SA ] [D] [ 0] run_stack_tti: stack called
2022-05-18T13:18:41.176363 [PHY-SA ] [D] [ 0] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.176363 [PHY-SA ] [D] [ 0] SYNC: state=IDLE, tti=0
2022-05-18T13:18:41.176364 [PHY-SA ] [D] [ 0] Discarding samples and sending tx_end
2022-05-18T13:18:41.176386 [MAC ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.176386 [MAC ] [D] [ 0] Update Bj: lcid=0, Bj=0
2022-05-18T13:18:41.176386 [MAC ] [D] [ 0] Update Bj: lcid=1, Bj=0
2022-05-18T13:18:41.176387 [MAC ] [D] [ 0] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.176387 [MAC-NR ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.176389 [MAC-NR ] [D] [ 0] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.176390 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:41.177402 [PHY-SA ] [D] [ 0] run_stack_tti: from recv
2022-05-18T13:18:41.177402 [PHY-SA ] [D] [ 0] run_stack_tti: calling stack tti=0, tti_jump=1
2022-05-18T13:18:41.177402 [PHY-SA ] [D] [ 0] run_stack_tti: stack called
2022-05-18T13:18:41.177402 [PHY-SA ] [D] [ 0] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.177403 [PHY-SA ] [D] [ 0] SYNC: state=IDLE, tti=0
2022-05-18T13:18:41.177403 [PHY-SA ] [D] [ 0] Discarding samples and sending tx_end
2022-05-18T13:18:41.177404 [MAC ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.177404 [MAC ] [D] [ 0] Update Bj: lcid=0, Bj=0
2022-05-18T13:18:41.177404 [MAC ] [D] [ 0] Update Bj: lcid=1, Bj=0
2022-05-18T13:18:41.177405 [MAC ] [D] [ 0] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.177405 [MAC-NR ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.177406 [MAC-NR ] [D] [ 0] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.177406 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:41.301373 [PHY-SA ] [D] [ 0] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.301412 [MAC ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.301412 [MAC ] [D] [ 0] Update Bj: lcid=0, Bj=0
2022-05-18T13:18:41.301412 [MAC ] [D] [ 0] Update Bj: lcid=1, Bj=0
2022-05-18T13:18:41.301412 [MAC ] [D] [ 0] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.301413 [MAC-NR ] [D] [ 0] Running MAC tti=0
2022-05-18T13:18:41.301413 [MAC-NR ] [D] [ 0] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.301414 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:41.301861 [PHY-SA ] [D] [ 0] SYNC: state=SEARCH, tti=0
2022-05-18T13:18:41.301861 [PHY-SA ] [D] [ 0] Discarding samples and sending tx_end
2022-05-18T13:18:41.301931 [RRC-NR ] [I] Proc “Cell Selection” - Cell search found ARFCN=0 PCI=500 epre=-45.9 snr=+0.2 cfo=-850.5 delay=+0.1 sfn=977 ssb_idx=0 hrf=n scs=15 ssb_offset=6 dmrs_typeA_pos=pos2 coreset0=6 ss0=0 barred=n intra_freq_reselection=y spare=0
2022-05-18T13:18:41.301948 [PHY-SA ] [I] [ 0] Cell Select: Going to IDLE
2022-05-18T13:18:41.301956 [PHY-SA ] [I] [ 0] Tuning Rx channel 0 to 1842.50 MHz
2022-05-18T13:18:41.301958 [RF ] [I] Mapping RF channel 0 (device=0, channel=0) to logical carrier 0 on f_rx=1842.5 MHz
2022-05-18T13:18:41.301960 [RF ] [I] RF Rx channel 0 already on freq
2022-05-18T13:18:41.301960 [PHY-SA ] [I] [ 0] Tuning Tx channel 0 to 1747.50 MHz
2022-05-18T13:18:41.301961 [RF ] [I] Mapping RF channel 0 (device=0, channel=0) to logical carrier 0 on f_tx=1747.5 MHz
2022-05-18T13:18:41.302414 [PHY-SA ] [D] [ 0] run_stack_tti: from recv
2022-05-18T13:18:41.302415 [PHY-SA ] [D] [ 0] run_stack_tti: calling stack tti=0, tti_jump=1
2022-05-18T13:18:41.302415 [PHY-SA ] [D] [ 0] run_stack_tti: stack called
2022-05-18T13:18:41.302415 [PHY-SA ] [D] [ 0] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.302416 [PHY-SA ] [D] [ 0] SYNC: state=IDLE, tti=0
2022-05-18T13:18:41.302416 [PHY-SA ] [D] [ 0] Discarding samples and sending tx_end
2022-05-18T13:18:41.435490 [MAC ] [D] [ 9905] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.435490 [PHY-SA ] [D] [ 9906] SYNC: state=CAMPING, tti=9906
2022-05-18T13:18:41.435490 [MAC-NR ] [D] [ 9905] Running MAC tti=9905
2022-05-18T13:18:41.435492 [MAC-NR ] [D] [ 9905] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.435493 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:41.435568 [PHY0-NR] [D] [ 9905] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 EPRE=-24.13, RSRP=-43.24, corr=0.012 nof_bits=39 crc=KO
2022-05-18T13:18:41.435568 [PHY0-NR] [D] [ 9905] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 EPRE=-24.13, RSRP=-43.24, corr=0.012 nof_bits=39 crc=KO
2022-05-18T13:18:41.435569 [PHY0-NR] [D] [ 9905] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=4 EPRE=-22.30, RSRP=-37.99, corr=0.027 nof_bits=39 crc=KO
2022-05-18T13:18:41.435571 [PHY0-NR] [D] [ 9905] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=4 EPRE=-22.30, RSRP=-37.99, corr=0.027 nof_bits=39 crc=KO
2022-05-18T13:18:41.435572 [PHY0-NR] [D] [ 9905] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=3 cce=0 EPRE=-23.12, RSRP=-43.76, corr=0.009 nof_bits=39 crc=KO
2022-05-18T13:18:41.435572 [PHY0-NR] [D] [ 9905] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=3 cce=0 EPRE=-23.12, RSRP=-43.76, corr=0.009 nof_bits=39 crc=KO
2022-05-18T13:18:41.435577 [RF ] [D] Detected RF overlap of 500.0 us. Discarding 3840 samples.
2022-05-18T13:18:41.436262 [PHY-SA ] [D] [ 9906] run_stack_tti: from recv
2022-05-18T13:18:41.436262 [PHY-SA ] [D] [ 9906] run_stack_tti: calling stack tti=9906, tti_jump=1
2022-05-18T13:18:41.436262 [PHY-SA ] [D] [ 9906] run_stack_tti: stack called
2022-05-18T13:18:41.436262 [PHY-SA ] [D] [ 9906] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.436271 [PHY-SA ] [D] [ 9907] SYNC: state=CAMPING, tti=9907
2022-05-18T13:18:41.436307 [MAC ] [D] [ 9906] Running MAC tti=9906
2022-05-18T13:18:41.436307 [MAC ] [D] [ 9906] Update Bj: lcid=0, Bj=0
2022-05-18T13:18:41.436307 [MAC ] [D] [ 9906] Update Bj: lcid=1, Bj=0
2022-05-18T13:18:41.436308 [MAC ] [D] [ 9906] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.436308 [MAC-NR ] [D] [ 9906] Running MAC tti=9906
2022-05-18T13:18:41.436310 [MAC-NR ] [D] [ 9906] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.436311 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:41.436354 [PHY1-NR] [D] [ 9906] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 EPRE=-25.68, RSRP=-47.53, corr=0.007 nof_bits=39 crc=KO
2022-05-18T13:18:41.436355 [PHY1-NR] [D] [ 9906] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 EPRE=-25.68, RSRP=-47.53, corr=0.007 nof_bits=39 crc=KO
2022-05-18T13:18:41.436355 [PHY1-NR] [D] [ 9906] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=4 EPRE=-22.30, RSRP=-39.83, corr=0.018 nof_bits=39 crc=KO
2022-05-18T13:18:41.436356 [PHY1-NR] [D] [ 9906] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=4 EPRE=-22.30, RSRP=-39.83, corr=0.018 nof_bits=39 crc=KO
2022-05-18T13:18:41.436356 [PHY1-NR] [D] [ 9906] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=3 cce=0 EPRE=-23.67, RSRP=-46.82, corr=0.005 nof_bits=39 crc=KO
2022-05-18T13:18:41.436357 [PHY1-NR] [D] [ 9906] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=3 cce=0 EPRE=-23.67, RSRP=-46.82, corr=0.005 nof_bits=39 crc=KO
2022-05-18T13:18:41.436360 [RF ] [D] Detected RF overlap of 500.0 us. Discarding 3840 samples.
2022-05-18T13:18:41.437303 [PHY-SA ] [D] [ 9907] run_stack_tti: from recv
2022-05-18T13:18:41.437303 [PHY-SA ] [D] [ 9907] run_stack_tti: calling stack tti=9907, tti_jump=1
2022-05-18T13:18:41.437304 [PHY-SA ] [D] [ 9907] run_stack_tti: stack called
2022-05-18T13:18:41.437304 [PHY-SA ] [D] [ 9907] SYNC: received 7680 samples from radio
2022-05-18T13:18:41.437313 [PHY-SA ] [D] [ 9908] SYNC: state=CAMPING, tti=9908
2022-05-18T13:18:41.437327 [MAC ] [D] [ 9907] Running MAC tti=9907
2022-05-18T13:18:41.437327 [MAC ] [D] [ 9907] Update Bj: lcid=0, Bj=0
2022-05-18T13:18:41.437327 [MAC ] [D] [ 9907] Update Bj: lcid=1, Bj=0
2022-05-18T13:18:41.437328 [MAC ] [D] [ 9907] RA: IDLE: rar_window_start=-1, rar_window_length=-1
2022-05-18T13:18:41.437329 [MAC-NR ] [D] [ 9907] Running MAC tti=9907
2022-05-18T13:18:41.437330 [MAC-NR ] [D] [ 9907] nof_lcids_with_data=0, nof_lcgs_with_data=0, last_non_zero_lcg=-1 [lcg0=0, ] [lcid0=0, ]
2022-05-18T13:18:41.437331 [STCK ] [D] tti_tprof: duration=0 msec
2022-05-18T13:18:49.105275 [PHY-SA ] [D] [ 6565] SYNC: received 7680 samples from radio
2022-05-18T13:18:49.105365 [PHY1-NR] [D] [ 6565] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 EPRE=-23.07, RSRP=-45.54, corr=0.006 nof_bits=39 crc=KO
2022-05-18T13:18:49.105366 [PHY1-NR] [D] [ 6565] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=0 EPRE=-23.07, RSRP=-45.54, corr=0.006 nof_bits=39 crc=KO
2022-05-18T13:18:49.105367 [PHY1-NR] [D] [ 6565] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=4 EPRE=-21.07, RSRP=-40.25, corr=0.012 nof_bits=39 crc=KO
2022-05-18T13:18:49.105367 [PHY1-NR] [D] [ 6565] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=2 cce=4 EPRE=-21.07, RSRP=-40.25, corr=0.012 nof_bits=39 crc=KO
2022-05-18T13:18:49.105367 [PHY1-NR] [D] [ 6565] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=3 cce=0 EPRE=-21.96, RSRP=-50.16, corr=0.002 nof_bits=39 crc=KO
2022-05-18T13:18:49.105368 [PHY1-NR] [D] [ 6565] PDCCH: si-rnti=0xffff dci=1_0 ss=common0 L=3 cce=0 EPRE=-21.96, RSRP=-50.16, corr=0.002 nof_bits=39 crc=KO
2022-05-18T13:18:49.105371 [RF ] [D] Detected RF overlap of 500.0 us. Discarding 3840 samples.
2022-05-18T13:18:49.161077 [RF ] [I] Underflow
Your help is highly appreciated.