PRACH fails

This is merely a historical archive of years 2008-2021, before the migration to mailman3.

A maintained and still updated list archive can be found at https://lists.osmocom.org/hyperkitty/list/OpenBSC@lists.osmocom.org/.

Sipos Csaba sipos.csaba at kvk.uni-obuda.hu
Thu Sep 8 10:56:45 UTC 2016


Dear Tom and List,

I am in the process of updating the wiki for USRP radios, therefor I am recompiling everything Osmocom related with the latest master branches.

I managed to setup the system, the CS services are working just fine (only fullrate and SMS is tested yet), but the GPRS service seems to be not working. I am using my well tested setup on 1800MHz (ARFCN 885), with duplex filters.

On the Osmo-BTS end I get this message:

<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access
<0007> l1sap.c:957 RACH for packet access

Osmo-PCU in the same time shows the following (repeatedly):

Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:819 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) timer 3169 expired.
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:874 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) T3169 timeout during transsmission
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:893 - Assignment was on CCCH
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:899 - No uplink data received yet
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:879 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) will be freed due to timeout
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:334 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) free
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> bts.cpp:1501 PDCH(TS 6, TRX 0): Detaching TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN), 0 TBFs, USFs = 00, TFIs = 00000000.
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> gprs_ms.cpp:323 Detaching TBF from MS object, TLLI = 0x00000000, TBF = TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN)
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> gprs_ms.cpp:140 Destroying MS object, TLLI = 0x00000000
Thu Sep  8 12:48:30 2016 DRLCMAC <0002> tbf.cpp:355 ********** TBF ends here **********
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:486 MS requests UL TBF on RACH, so we provide one:
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> tbf.cpp:672 ********** TBF starts here **********
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> tbf.cpp:674 Allocating UL TBF: MS_CLASS=0/0
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_ms.cpp:114 Creating MS object, TLLI = 0x00000000
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:413 Searching for first unallocated TFI: TRX=0
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:423  Found TFI=0.
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:525 Slot Allocation (Algorithm B) for unknown class (assuming 12)
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:560 - Rx=4 Tx=4 Sum Rx+Tx=5  Tta=2 Ttb=1  Tra=2 Trb=1 Type=1
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 0, because not enabled
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 1, because not enabled
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 2, because not enabled
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 3, because not enabled
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:579 - Possible DL/UL slots: (TS=0)"....CCCC"(TS=7)
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:940 - Selected UL slots: (TS=0)"......U."(TS=7), single
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:966 Using single slot at TS 6 for UL
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:990 - Reserved DL/UL slots: (TS=0)"......C."(TS=7)
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:1017 - Assigning UL TS 6
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:1481 PDCH(TS 6, TRX 0): Attaching TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL), 1 TBFs, USFs = 01, TFIs = 00000001.
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> tbf.cpp:385 - Setting Control TS 6
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> gprs_ms.cpp:267 Attaching TBF to MS object, TLLI = 0x00000000, TBF = TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL)
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> tbf.cpp:625 Allocated TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL): trx = 0, ul_slots = 40, dl_slots = 00
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:291 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL) changes state from NULL to FLOW
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> tbf.cpp:409 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) starting timer 3169.
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:530 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) [UPLINK] START
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:534 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) RX: [PCU <- BTS] RACH qbit-ta=0 ra=0x78, Fn=1011875 (27,35,7)
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:536 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) TX: START Immediate Assignment Uplink (AGCH)
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:549  - TRX=0 (885) TS=6 TA=0 TSC=7 TFI=0 USF=0
Thu Sep  8 12:48:31 2016 DRLCMAC <0002> bts.cpp:291 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) changes state from FLOW to WAIT ASSIGN
Thu Sep  8 12:48:33 2016 DBSSGP <0009> gprs_bssgp_pcu.cpp:792 Sending flow control info on BVCI 2
Thu Sep  8 12:48:33 2016 DBSSGP <0009> gprs_bssgp_pcu.cpp:705 Computed BVC leak rate = 10000, num_pdch = 4, cs = CS-4
Thu Sep  8 12:48:33 2016 DBSSGP <0009> gprs_bssgp_pcu.cpp:729 Computed MS default leak rate = 10000, ms_num_pdch = 4, cs = CS-4
Thu Sep  8 12:48:33 2016 DBSSGP <0009> gprs_bssgp_pcu.cpp:758 Sending FLOW CONTROL BVC, Bmax = 100000, R = 10000, Bmax_MS = 100000, R_MS = 10000, avg_dly = 0
Thu Sep  8 12:48:33 2016 DBSSGP <0009> gprs_bssgp_pcu.cpp:385 rx BVCI_PTP gprs_bssgp_rx_ptp
Thu Sep  8 12:48:33 2016 DBSSGP <0009> gprs_bssgp_pcu.cpp:245 rx BSSGP_PDUT_FLOW_CONTROL_BVC_ACK
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:819 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) timer 3169 expired.
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:874 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) T3169 timeout during transsmission
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:893 - Assignment was on CCCH
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:899 - No uplink data received yet
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:879 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) will be freed due to timeout
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:334 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) free
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:1501 PDCH(TS 6, TRX 0): Detaching TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN), 0 TBFs, USFs = 00, TFIs = 00000000.
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_ms.cpp:323 Detaching TBF from MS object, TLLI = 0x00000000, TBF = TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_ms.cpp:140 Destroying MS object, TLLI = 0x00000000
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:355 ********** TBF ends here **********
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:486 MS requests UL TBF on RACH, so we provide one:
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:672 ********** TBF starts here **********
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:674 Allocating UL TBF: MS_CLASS=0/0
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_ms.cpp:114 Creating MS object, TLLI = 0x00000000
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:413 Searching for first unallocated TFI: TRX=0
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:423  Found TFI=0.
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:525 Slot Allocation (Algorithm B) for unknown class (assuming 12)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:560 - Rx=4 Tx=4 Sum Rx+Tx=5  Tta=2 Ttb=1  Tra=2 Trb=1 Type=1
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 0, because not enabled
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 1, because not enabled
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 2, because not enabled
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 3, because not enabled
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:579 - Possible DL/UL slots: (TS=0)"....CCCC"(TS=7)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:940 - Selected UL slots: (TS=0)"......U."(TS=7), single
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:966 Using single slot at TS 6 for UL
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:990 - Reserved DL/UL slots: (TS=0)"......C."(TS=7)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_rlcmac_ts_alloc.cpp:1017 - Assigning UL TS 6
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:1481 PDCH(TS 6, TRX 0): Attaching TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL), 1 TBFs, USFs = 01, TFIs = 00000001.
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:385 - Setting Control TS 6
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> gprs_ms.cpp:267 Attaching TBF to MS object, TLLI = 0x00000000, TBF = TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:625 Allocated TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL): trx = 0, ul_slots = 40, dl_slots = 00
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:291 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL) changes state from NULL to FLOW
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> tbf.cpp:409 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) starting timer 3169.
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:530 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) [UPLINK] START
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:534 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) RX: [PCU <- BTS] RACH qbit-ta=0 ra=0x79, Fn=1013049 (27,36,11)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:536 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) TX: START Immediate Assignment Uplink (AGCH)
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:549  - TRX=0 (885) TS=6 TA=0 TSC=7 TFI=0 USF=0
Thu Sep  8 12:48:36 2016 DRLCMAC <0002> bts.cpp:291 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) changes state from FLOW to WAIT ASSIGN
Thu Sep  8 12:48:41 2016 DRLCMAC <0002> tbf.cpp:819 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) timer 3169 expired.
Thu Sep  8 12:48:41 2016 DRLCMAC <0002> tbf.cpp:874 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=WAIT ASSIGN) T3169 timeout during transsmission

The SGSN and GGSN logs are empty.

The RX gain is on 5dB, the UHD version is 3.10.0-release. Only GPRS is allowed so far on 4 PDCHs.

Osmo-TRX is started with default set:

Config Settings
   Log Level............... NOTICE
   Device args.............
   TRX Base Port........... 5700
   TRX Address............. 127.0.0.1
   Channels................ 1
   Tx Samples-per-Symbol... 4
   Rx Samples-per-Symbol... 1
   EDGE support............ Disabled
   Reference............... Internal
   C0 Filler Table......... Disabled
   Multi-Carrier........... Disabled
   Diversity............... Disabled
   Tuning offset........... 0
   RSSI to dBm offset...... 0
   Swap channels........... 0


The CS call and SMS are both working fine, the audio quality is quite good.

Can someone give me a hint where this goes wrong? From what I see the PRACH is failing to succeed.

Regards,
Csaba



More information about the OpenBSC mailing list