Project

General

Profile

Feature #4362

osmo-trx-lms: Support multi-arfcn feature

Added by pespin 15 days ago. Updated 14 days ago.

Status:
In Progress
Priority:
Normal
Assignee:
Category:
LimeSDR
Target version:
-
Start date:
01/13/2020
Due date:
% Done:

50%

Spec Reference:

Description

According to [1], we cannot use the two channels of LimeSDR devices on different frequencies. However, we may still be able to serve several TRX using multi-arfcn feature in osmo-trx, by muxing them into the same physical channel.

[1] https://github.com/myriadrf/LimeSuite/issues/276


Related issues

Related to OsmoTRX - Bug #3346: osmo-trx-lms: Multi channel support: "R_CTL_LPF range limit reached"Rejected06/13/2018

Related to OsmoTRX - Bug #1963: multiplexing trx is not workingClosed03/03/2017

History

#1 Updated by pespin 15 days ago

  • Related to Bug #3346: osmo-trx-lms: Multi channel support: "R_CTL_LPF range limit reached" added

#2 Updated by pespin 15 days ago

  • Related to Bug #1963: multiplexing trx is not working added

#3 Updated by pespin 15 days ago

I have done some related work here:
https://git.osmocom.org/osmo-trx/log/?h=pespin/lms-multiarfcn

After those changes I can start osmo-trx-lms using multi-arfcn with same working setup I use with osmo-trx-uhd. I'm using DCS1800 ARFCN 870 and 874 for TRX0 and TRX1.

Using osmocom-bb's rssibin on a motorola phone I can properly see on Spectrum View signal hills on ARFCNs 870 and 874 with RSSI around -53 and -60, and android phones can see the network fine.

However, In current state, there's several issues preventing its use:
  • [A] I see a bunch of packet losses in osmo-trx-lms around every 20 seconds. However, CPU usage seems fine, I'm running with realtime prio 18 and I see no CPU being ever used more than 50-70%, so I suspect it may be some bus issue. I have the issue using both USB2 and USB3. (see logs below):
  • [B] Android phone seems to be unable to register. I see RACH requests being received in GSMTAP when the MS tries to register, but osmo-bts-trx drops them due to ToA value exceeding allowed range (see logs below).
  • [C] osmocom-bb's mobile+osmocon+motolaCXXX also has the same same issue, no RACH is answered (and osmo-bts-trx shows same ToA issues), and sometimes mobile even fails to sync (see logs below).

A:

Mon Jan 13 18:04:54 2020 DTRXCLK <0001> Transceiver.cpp:1122 [tid=140671359866624] sending IND CLOCK 65854
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21476 -> 21477)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21477 -> 21478)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21478 -> 21479)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21479 -> 21480)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21480 -> 21481)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21481 -> 21482)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEVDRV <0006> LMSDevice.cpp:95 [tid=140671385044736] L
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21482 -> 21484)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21484 -> 21485)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21485 -> 21486)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21486 -> 21487)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21487 -> 21488)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21488 -> 21489)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21489 -> 21490)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21490 -> 21492)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21492 -> 21493)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21493 -> 21494)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21494 -> 21496)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21496 -> 21497)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21497 -> 21498)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21498 -> 21499)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21499 -> 21500)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21500 -> 21502)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21502 -> 21503)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21503 -> 21504)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21504 -> 21505)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21505 -> 21506)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21506 -> 21507)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21507 -> 21509)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21509 -> 21510)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21510 -> 21511)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21511 -> 21513)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21513 -> 21514)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21514 -> 21515)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEVDRV <0006> LMSDevice.cpp:95 [tid=140671385044736] L
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21515 -> 21517)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21517 -> 21518)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21518 -> 21520)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21520 -> 21522)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DDEV <0005> LMSDevice.cpp:751 [tid=140671368259328][chan=0] Tx Dropped packets by HW! (21522 -> 21523)
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:167 [tid=140671368259328][chan=0] Received counter change from radioDevice
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:125 [tid=140671484665152] Main thread is updating counters
Mon Jan 13 18:04:54 2020 DMAIN <0000> trx_rate_ctr.cpp:130 [tid=140671484665152][chan=0] rate_ctr update
Mon Jan 13 18:04:55 2020 DTRXCLK <0001> Transceiver.cpp:1122 [tid=140671359866624] sending IND CLOCK 66070

B:

20200113181735441 DTRX <000b> trx_if.c:122 phy0.0: Clock indication: fn=230768
20200113181735441 DL1C <0006> scheduler_trx.c:1800 TRX Clock Ind: elapsed_us= 997831, elapsed_fn=216, error_us= +991
20200113181735441 DL1C <0006> scheduler_trx.c:1819 GSM clock jitter: -3390us (elapsed_fn=0)
20200113181735570 DL1C <0006> l1sap.c:1373 230791/174/15/16/31 Ignoring an Access Burst on CCCH: ToA(-636) exceeds the allowed range (-512..16128)
20200113181736439 DTRX <000b> trx_if.c:122 phy0.0: Clock indication: fn=230984
20200113181736439 DL1C <0006> scheduler_trx.c:1800 TRX Clock Ind: elapsed_us= 997520, elapsed_fn=216, error_us= +680
20200113181736439 DL1C <0006> scheduler_trx.c:1819 GSM clock jitter: -4030us (elapsed_fn=0)
20200113181736581 DL1C <0006> l1sap.c:1373 231010/174/00/31/46 Ignoring an Access Burst on CCCH: ToA(-646) exceeds the allowed range (-512..16128)
20200113181737436 DTRX <000b> trx_if.c:122 phy0.0: Clock indication: fn=231200
20200113181737436 DL1C <0006> scheduler_trx.c:1800 TRX Clock Ind: elapsed_us= 997045, elapsed_fn=216, error_us= +205
20200113181737436 DL1C <0006> scheduler_trx.c:1819 GSM clock jitter: -4120us (elapsed_fn=0)
20200113181737711 DL1C <0006> l1sap.c:1373 231255/174/11/21/35 Ignoring an Access Burst on CCCH: ToA(-643) exceeds the allowed range (-512..16128)
20200113181738434 DTRX <000b> trx_if.c:122 phy0.0: Clock indication: fn=231416

C:

HERE LU FAILURE DUE TO NO ANSWER:
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2841 Found 1 frequencies.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2258 Scanning frequency 870(DCS) (rxlev -55).
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:477 Sync to ARFCN=870(DCS) rxlev=-55 (No sysinfo yet, ccch mode NONE)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2278 40 frequencies left in band 512..885
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2950 Channel synched. (ARFCN=870(DCS), snr=16, BSIC=63)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:703 Starting CS timer with 4 seconds.
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2973 using DSC of 90
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:4814 Channel provides data.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:703 Starting CS timer with 4 seconds.
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1814 New SYSTEM INFORMATION 2
<0001> /osmocom-bb/src/host/layer23/src/common/sysinfo.c:703 New SYSTEM INFORMATION 3 (mcc 234 mnc 70 lac 0x0005)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1905 Changing CCCH_MODE to 2
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1937 New SYSTEM INFORMATION 4 (mcc 234 mnc 70 lac 0x0005)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1872 New SYSTEM INFORMATION 2ter
<0001> /osmocom-bb/src/host/layer23/src/common/sysinfo.c:615 Now updating previously received SYSTEM INFORMATION 4
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1785 New SYSTEM INFORMATION 1
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2552 New BA list (mcc=234 mnc=70  Guernsey, 70).
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2719 Received relevant sysinfo.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:713 stopping pending CS timer.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2327 Scan frequency 870(DCS): Cell found. (rxlev -55 mcc 234 mnc 70 lac 0005)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:1841 Select using access class
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:383 A (RLA_C (-55) - RXLEV_ACC_MIN (-110)) = 55
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:385 B (MS_TXPWR_MAX_CCH (32) - p (30)) = 2
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:387 C1 (A - MAX(B,0)) = 53
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:1955 Cell ARFCN 870(DCS): Cell found, (rxlev=-55 mcc=234 mnc=70 lac=0005  Guernsey, 70)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:1970 Cell ARFCN 870(DCS) selected.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2428 Tune to frequency 870.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:464 Sync to ARFCN=870(DCS) rxlev=-55 (Sysinfo, ccch mode COMB)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2455 Cell available.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:4054 (ms mymobile) Event 'EVENT_CELL_FOUND' for Cell selection in state 'C1 normal cell selection'
<000e> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:3386 Camping normally on cell (ARFCN=870(DCS) mcc=234 mnc=70  Guernsey, 70)
...
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:357 new state idle -> connection pending
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1444 CHANNEL REQUEST: 00 (Location Update with NECI)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2950 Channel synched. (ARFCN=870(DCS), snr=16, BSIC=63)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2973 using DSC of 90
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:4814 Channel provides data.
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 8)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 0 ra 0x01)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 7)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x07)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-54 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 6)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x07)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 5)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x0e)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 4)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x05)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 3)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x01)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-54 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 2)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x00)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 1)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1639 RANDOM ACCESS (Tx-integer 12 combined yes S(lots) 115 ra 0x0d)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1675 Use MS-TXPWR-MAX-CCH power value 31 (32 dBm)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber= 10 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1583 RANDOM ACCESS (requests left 0)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:1587 Done with sending RANDOM ACCESS bursts
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:813 starting T3126 with 5.000 seconds
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-54 snr= 0 ber=  9 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-54 snr= 0 ber= 12 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-55 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:752 timer T3126 has fired
<000e> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:757 Requesting channel failed
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:357 new state connection pending -> idle
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:4054 (ms mymobile) Event 'EVENT_RET_IDLE' for Cell selection in state 'connected mode 1'

...
HERE SYNC failure:
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: no cell info
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2950 Channel synched. (ARFCN=870(DCS), snr=16, BSIC=63)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2973 using DSC of 90
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:4814 Channel provides data.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:477 Sync to ARFCN=114 rxlev=<=-110 (No sysinfo yet, ccch mode NONE)
<000c> /osmocom-bb/src/host/layer23/src/common/l1ctl.c:169 FBSB RESP: result=255
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2995 Channel sync error, try again
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:477 Sync to ARFCN=114 rxlev=<=-110 (No sysinfo yet, ccch mode NONE)
<000c> /osmocom-bb/src/host/layer23/src/common/l1ctl.c:169 FBSB RESP: result=255
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:3008 Channel sync error.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:3012 free sysinfo ARFCN=114
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:477 Sync to ARFCN=880(DCS) rxlev=<=-110 (No sysinfo yet, ccch mode NONE)
<000c> /osmocom-bb/src/host/layer23/src/common/l1ctl.c:169 FBSB RESP: result=255
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2995 Channel sync error, try again
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:477 Sync to ARFCN=880(DCS) rxlev=<=-110 (No sysinfo yet, ccch mode NONE)
<000c> /osmocom-bb/src/host/layer23/src/common/l1ctl.c:169 FBSB RESP: result=255
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:3008 Channel sync error.
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:3012 free sysinfo ARFCN=880(DCS)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:464 Sync to ARFCN=870(DCS) rxlev=-55 (Sysinfo, ccch mode COMB)
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2950 Channel synched. (ARFCN=870(DCS), snr=16, BSIC=63)
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm322.c:2973 using DSC of 90
<0003> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:4814 Channel provides data.
<000c> /osmocom-bb/src/host/layer23/src/common/l1ctl.c:299 Dropping frame with 66 bit errors
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-70 snr=60 ber= 23 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-50 snr= 0 ber=  0 LAI=234 70 0005 ID=0000
<0001> /osmocom-bb/src/host/layer23/src/mobile/gsm48_rr.c:665 MON: f=870 lev=-50 snr= 0 ber=  0 LAI=234 70 0005 ID=0000

So, in summary, TODO is to investigate:
  • Rx issues probably cause by timing issues (ToA?)
  • Packets lost events coming from osmo-trx-lms

#4 Updated by fixeria 15 days ago

Hi Pau,

Ignoring an Access Burst on CCCH: ToA(-636) exceeds the allowed range (-512..16128)

that's expected since you're changing the sample rate in I2b8c2c6738c494b8d56ebcb073106c864ab191dc.

Take a look at: https://git.osmocom.org/osmo-trx/tree/Transceiver52M/device/uhd/UHDDevice.cpp#n100. I believe we need a similar table for LimeSDR devices where we can define the timing correction depending on device type and sample rate.

#5 Updated by fixeria 15 days ago

Currently it's hard-coded here: https://git.osmocom.org/osmo-trx/tree/Transceiver52M/device/lms/LMSDevice.cpp#n237. I see that you're changing this line in your patch, but you probably also need to change the coefficient - 8.9e-5 (try lower values?).

#6 Updated by pespin 14 days ago

  • Status changed from New to In Progress
  • % Done changed from 0 to 50

Working Initial multi-arfcn support is introduced here. I tested it with LimeSDR-USB and LimeSDR-mini.
remote: https://gerrit.osmocom.org/c/osmo-trx/+/16875 lms: Move rx_buffers allocation to constructor
remote: https://gerrit.osmocom.org/c/osmo-trx/+/16876 lms: Store device type specific parameters in one place
remote: https://gerrit.osmocom.org/c/osmo-trx/+/16877 lms: Make ts_offset and smpl rate coefs device-specific
remote: https://gerrit.osmocom.org/c/osmo-trx/+/16878 lms: Initial multi-arfcn support

TODO:
  • Test multi-arfcn manually and fix ts_offset coef if required.
  • Enable running multi-arfcn test for LimeSDR-USB and LimeNet-micro in osmo-gsm-tester
  • Investigate bursts of Tx packet drop events around every 20 seconds.
  • Add extra checks detected missing in LMSDevice::open():
    • Check chans is never > 1 after calling radioDevice parent constructor (multi channel not supported by any device)
    • Make sure tx_sps and rx_sps is always 4.
    • When on multi-arfcn, check RX_PATH/TX_PATH for TRX!=0 are the same if set as the ones set in TRX0.

Also available in: Atom PDF

Add picture from clipboard (Maximum size: 48.8 MB)