[srslte-users] Can't connect to commercial EnodeB with athonet

Jonathan Borgeaud jonathan.borgeaud at jbtech.ch
Thu Nov 24 08:13:24 UTC 2016


Pcap is here: https://ufile.io/a7629


and the log:



09:03:53.702 [MAC ] Info    [00000] Resetting MAC
09:03:53.703 [PHY ] Info    [00000] PHY:   Configuring UL parameters
09:03:53.703 [PHY ] Info    [00000] Setting new params worker_id=0, pregen_disabled=0
09:03:53.703 [PHY ] Info    [00000] Setting new params worker_id=1, pregen_disabled=0
09:03:53.703 [RRC ] Info    Set MAC main config: harq-MaxReTX=5, bsr-TimerReTX=2560, bsr-TimerPeriodic=-1
09:03:53.705 [MAC ] Info    [00000] Waiting PHY to synchronize with cell
09:03:54.769 [RLC ] Info    BCCH BCH message received.
             0000: 43 18 00
09:03:54.769 [RRC ] Info    BCCH BCH message received.
             0000: 43 18 00
09:03:54.769 [RRC ] Info    BCCH BCH message Stack latency: 20 us
09:03:54.769 [RRC ] Info    MIB received BW=5 MHz
09:03:56.997 [PHY ] Info    [00000] SYNC:  Cell found. Synchronizing...
09:03:57.012 [RRC ] Info    Detected 1 out-of-sync from PHY. Starting T310 timer
09:03:57.015 [PHY ] Info    [00000] SYNC:  Decoding MIB...
09:03:57.025 [PHY ] Info    [00000] SYNC:  Decoding MIB...
09:03:57.035 [PHY ] Info    [00000] SYNC:  Decoding MIB...
09:03:57.045 [PHY ] Info    [00000] SYNC:  Decoding MIB...
09:03:57.046 [PHY ] Info    [00000] SYNC:  DONE, TTI=10230, sfn_offset=3
09:03:57.055 [RRC ] Info    Detected 1 in-sync from PHY. Stopping T310 timer
09:03:57.056 [MAC ] Info    [00002] BSR:   Configured timer reTX 2560 ms
09:03:57.080 [MAC ] Info    [00026] SCHED: Searching for DL grant for SI-RNTI window_st=45, window_len=1
09:03:57.190 [MAC ] Info    [00136] SCHED: Searching for DL grant for SI-RNTI window_st=145, window_len=1
09:03:57.301 [MAC ] Info    [00246] SCHED: Searching for DL grant for SI-RNTI window_st=265, window_len=1
09:03:57.320 [PHY ] Info    [00265] PDCCH: DL DCI Format1C cce_index=12, L=4, n_data_bits=12, hex=[18 60 ]
09:03:57.321 [PHY ] Info    [00265] PDSCH: l_crb= 4, harq=0, tbs=22, mcs=0, rv=2, crc=OK, snr=24.6 dB, n_iter=1, dec_time=  86 us
09:03:57.321 [RLC ] Info    BCCH TXSCH message received.
             0000: 60 c8 88 20 80 08 06 00 06 00 00 a0 30 2f e3 04
             0010: 80 84 0c 00 00 00
09:03:57.321 [RRC ] Info    BCCH DLSCH message received.
             0000: 60 c8 88 20 80 08 06 00 06 00 00 a0 30 2f e3 04
             0010: 80 84 0c 00 00 00
09:03:57.321 [RRC ] Info    BCCH DLSCH message Stack latency: 26 us
09:03:57.321 [RRC ] Info    SIB1 received, CellID=1281, si_window=10, sib2_period=16
09:03:57.321 [MAC ] Info    [00266] DL 8:  reTX  tbs=22, rv=2, ack=OK, ndi=0 (0), tti=265 (0)
09:03:57.411 [MAC ] Info    [00356] SCHED: Searching for DL grant for SI-RNTI window_st=480, window_len=10
09:03:57.521 [MAC ] Info    [00466] SCHED: Searching for DL grant for SI-RNTI window_st=480, window_len=10
09:03:57.555 [MAC ] Info    [00501] BSR:   QUEUE status: 0 (0), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:03:57.631 [MAC ] Info    [00576] SCHED: Searching for DL grant for SI-RNTI window_st=640, window_len=10
09:03:57.741 [MAC ] Info    [00687] SCHED: Searching for DL grant for SI-RNTI window_st=800, window_len=10
09:03:57.851 [MAC ] Info    [00797] SCHED: Searching for DL grant for SI-RNTI window_st=800, window_len=10
09:03:57.961 [MAC ] Info    [00906] SCHED: Searching for DL grant for SI-RNTI window_st=960, window_len=10
09:03:58.056 [MAC ] Info    [01002] BSR:   QUEUE status: 0 (0), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:03:58.071 [MAC ] Info    [01017] SCHED: Searching for DL grant for SI-RNTI window_st=1120, window_len=10
09:03:58.181 [MAC ] Info    [01127] SCHED: Searching for DL grant for SI-RNTI window_st=1280, window_len=10
09:03:58.291 [MAC ] Info    [01237] SCHED: Searching for DL grant for SI-RNTI window_st=1280, window_len=10
09:03:58.402 [MAC ] Info    [01347] SCHED: Searching for DL grant for SI-RNTI window_st=1440, window_len=10
09:03:58.512 [MAC ] Info    [01458] SCHED: Searching for DL grant for SI-RNTI window_st=1600, window_len=10
09:03:58.557 [MAC ] Info    [01503] BSR:   QUEUE status: 0 (0), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:03:58.622 [MAC ] Info    [01568] SCHED: Searching for DL grant for SI-RNTI window_st=1600, window_len=10
09:03:58.732 [MAC ] Info    [01677] SCHED: Searching for DL grant for SI-RNTI window_st=1760, window_len=10
09:03:58.815 [PHY ] Info    [01760] PDCCH: DL DCI Format1C cce_index=12, L=4, n_data_bits=12, hex=[30 90 ]
09:03:58.815 [PHY ] Info    [01760] PDSCH: l_crb= 6, harq=0, tbs=32, mcs=0, rv=0, crc=OK, snr=23.6 dB, n_iter=1, dec_time=  87 us
09:03:58.815 [RLC ] Info    BCCH TXSCH message received.
             0000: 00 01 6f 20 f2 03 27 63 0d da 28 06 26 d0 72 90
             0010: 18 00 00 80 0a 11 55 a2 a8 27 62 35 3b 14 08 00
09:03:58.816 [RRC ] Info    BCCH DLSCH message received.
             0000: 00 01 6f 20 f2 03 27 63 0d da 28 06 26 d0 72 90
             0010: 18 00 00 80 0a 11 55 a2 a8 27 62 35 3b 14 08 00
09:03:58.816 [RRC ] Info    BCCH DLSCH message Stack latency: 53 us
09:03:58.816 [RRC ] Info    SIB2 received
09:03:58.816 [RRC ] Info    Set RACH ConfigCommon: NofPreambles=40, ResponseWindow=10, ContentionResolutionTimer=32 ms
09:03:58.816 [PHY ] Info    [01760] PHY:   Configuring UL parameters
09:03:58.816 [PHY ] Info    [01760] Setting new params worker_id=0, pregen_disabled=0
09:03:58.816 [PHY ] Info    [01760] Setting new params worker_id=1, pregen_disabled=0
09:03:58.816 [RRC ] Info    Set PUSCH ConfigCommon: HopOffset=6, RSGroup=0, RSNcs=0, N_sb=1
09:03:58.816 [RRC ] Info    Set PUCCH ConfigCommon: DeltaShift=1, CyclicShift=0, N1=10, NRB=2
09:03:58.816 [RRC ] Info    Set PRACH ConfigCommon: SeqIdx=12, HS=no, FreqOffset=3, ZC=13, ConfigIndex=19
09:03:58.816 [RRC ] Info    Set SRS ConfigCommon: BW-Configuration=0, SF-Configuration=0, ACKNACK=no
09:03:58.816 [RRC ] Info    Set Constants and Timers: N310=10, N311=1, t301=200, t310=2000, t311=3000
09:03:58.816 [RRC ] Info    Sending RRC Connection Request on SRB0
09:03:58.816 [PDCP] Info    TX SRB0 SDU, do_security = false
             0000: 50 00 00 00 3e 86
09:03:58.816 [RLC ] Info    SRB0 Tx SDU
             0000: 50 00 00 00 3e 86
09:03:58.816 [MAC ] Info    [01762] DL 8:  newTX tbs=32, rv=0, ack=OK, ndi=0 (0), tti=1760 (0)
09:03:58.817 [MAC ] Info    [01763] BSR:   Triggered REGULAR BSR for single LCID=0
09:03:58.817 [MAC ] Info    [01763] BSR:   Need to send sr: sr_is_sent=true, reset_sr=false, tti=1763, next_tx_tti=0
09:03:58.817 [MAC ] Info    [01763] SR:    PUCCH not configured. Starting RA procedure
09:03:58.817 [MAC ] Info    [01763] RA:    INIT:   Starting PRACH by MAC order
09:03:58.817 [PHY ] Error   [01762] Initiating PRACH library
09:03:58.817 [PHY ] Error   [01762] Configuring PRACH parameters
09:03:58.819 [PHY ] Error   [01764] PRACH not initiated
09:03:58.819 [PHY ] Error   [01764] Preparing PRACH to send
09:03:59.058 [MAC ] Info    [02004] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:03:59.559 [MAC ] Info    [02505] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:00.060 [MAC ] Info    [03006] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:00.561 [MAC ] Info    [03507] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:01.062 [MAC ] Info    [04008] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:01.563 [MAC ] Info    [04509] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:02.064 [MAC ] Info    [05010] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:02.565 [MAC ] Info    [05511] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),
09:04:03.066 [MAC ] Info    [06012] BSR:   QUEUE status: 6 (6), 0 (0), 0 (0), 0 (0), 0 (0), 0 (0),

It get stuck at this...

________________________________
From: Ismael Gomez <ismael.gomez at softwareradiosystems.com>
Sent: Wednesday, November 23, 2016 5:22:21 PM
To: Jonathan Borgeaud; srslte-users
Subject: Re: [srslte-users] Can't connect to commercial EnodeB with athonet

Can you enable the log level to info for all layers (all_level = info in the ue.conf) and also enable pcap and send us an attachment with the full /tmp/ue.log and /tmp/ue.pcap files please?

thank you

On Wed, 23 Nov 2016 at 13:22 Jonathan Borgeaud <jonathan.borgeaud at jbtech.ch<mailto:jonathan.borgeaud at jbtech.ch>> wrote:

Here is the output:


linux; GNU C++ version 6.2.1 20160830; Boost_106200; UHD_003.009.005-0-unknown

---  Software Radio Systems LTE UE  ---

Reading configuration file ue.conf...
Using srsLTE version 001.004.000
Opening bladeRF...
set RX frequency to 2687500033
set TX frequency to 2567500033
Setting frequency: DL=2687.5 Mhz, UL=2567.5 MHz
Searching for cell...
Set RX sampling rate 1.92 Mhz, filter BW: 2.50 Mhz
Found CELL ID: 3 CP: Normal  , CFO: -1.1 KHz.
Trying to decode MIB...
 - Cell ID:         3
 - Nof ports:       2
 - CP:              Normal
 - PRB:             25
 - PHICH Length:    Normal
 - PHICH Resources: 1/6
 - SFN:             0
MIB received BW=5 MHz
Initializating cell configuration...
Setting Sampling frequency 5.76 MHz
Set RX sampling rate 5.76 Mhz, filter BW: 5.00 Mhz
Setting TX/RX offset 16 samples, 2.78 us
SIB1 received, CellID=1281,  PLMN Id: MCC 222 MNC 10 PLMN Id: MCC 1 MNC 1
SIB2 received
Invalid parameters

After this it get stuck in a dead loop.

Here is the ue.log:


13:20:13.681 [PHY ] Info    [00000] PHY:   Configuring UL parameters
13:20:13.681 [PHY ] Info    [00000] Setting new params worker_id=0, pregen_disabled=0
13:20:13.681 [PHY ] Info    [00000] Setting new params worker_id=1, pregen_disabled=0
13:20:16.976 [PHY ] Info    [00000] SYNC:  Cell found. Synchronizing...
13:20:16.987 [PHY ] Info    [00000] SYNC:  Decoding MIB...
13:20:16.997 [PHY ] Info    [00000] SYNC:  Decoding MIB...
13:20:17.007 [PHY ] Info    [00000] SYNC:  Decoding MIB...
13:20:17.008 [PHY ] Info    [00000] SYNC:  DONE, TTI=4340, sfn_offset=2
13:20:19.392 [PHY ] Info    [06725] PDCCH: DL DCI Format1C cce_index=12, L=4, n_data_bits=12, hex=[18 60 ]
13:20:19.392 [PHY ] Info    [06725] PDSCH: l_crb= 4, harq=0, tbs=22, mcs=0, rv=0, crc=OK, snr=7.4 dB, n_iter=1, dec_time=  87 us
13:20:20.667 [PHY ] Info    [08000] PDCCH: DL DCI Format1C cce_index=12, L=4, n_data_bits=12, hex=[30 90 ]
13:20:20.667 [PHY ] Info    [08000] PDSCH: l_crb= 6, harq=0, tbs=32, mcs=0, rv=0, crc=OK, snr=7.5 dB, n_iter=1, dec_time=  84 us
13:20:20.668 [PHY ] Info    [08000] PHY:   Configuring UL parameters
13:20:20.668 [PHY ] Info    [08000] Setting new params worker_id=0, pregen_disabled=0
13:20:20.668 [PHY ] Info    [08000] Setting new params worker_id=1, pregen_disabled=0
13:20:20.668 [PHY ] Error   [08001] Initiating PRACH library
13:20:20.668 [PHY ] Error   [08001] Configuring PRACH parameters
13:20:20.670 [PHY ] Error   [08003] PRACH not initiated
13:20:20.670 [PHY ] Error   [08003] Preparing PRACH to send
Closing log%



________________________________
From: Ismael Gomez <ismael.gomez at softwareradiosystems.com<mailto:ismael.gomez at softwareradiosystems.com>>
Sent: Tuesday, November 22, 2016 5:16:36 PM
To: Jonathan Borgeaud; srslte-users
Subject: Re: [srslte-users] Can't connect to commercial EnodeB with athonet

Hi Jonathan,

Could you please send us the file /tmp/ue.log and the output of the ue program?

thank you


On Tue, 22 Nov 2016 at 16:15 Jonathan Borgeaud <jonathan.borgeaud at jbtech.ch<mailto:jonathan.borgeaud at jbtech.ch>> wrote:

Hi,
I can't connect to the commercial enode with latest version of srsUE.
When connecting,  i got sib1,  sib 2, then in the log I got a Invalid parameters.
How can I debug this further?
Thanks.
Jon

Sent from my mobile device

_______________________________________________
srslte-users mailing list
srslte-users at lists.softwareradiosystems.com<mailto:srslte-users at lists.softwareradiosystems.com>
http://www.softwareradiosystems.com/mailman/listinfo/srslte-users
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.softwareradiosystems.com/pipermail/srslte-users/attachments/20161124/ea4e8f24/attachment-0001.html>


More information about the srslte-users mailing list