Feature #4362
openosmo-trx-lms: Support multi-arfcn feature
50%
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.
Related issues
Updated by pespin over 4 years ago
- Related to Bug #3346: osmo-trx-lms: Multi channel support: "R_CTL_LPF range limit reached" added
Updated by pespin over 4 years ago
- Related to Bug #1963: multiplexing trx is not working added
Updated by pespin over 4 years 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=0000So, in summary, TODO is to investigate:
- Rx issues probably cause by timing issues (ToA?)
- Packets lost events coming from osmo-trx-lms
Updated by fixeria over 4 years 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.
Updated by fixeria over 4 years 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?).
Updated by pespin over 4 years 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
- 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.