USRP2 and OsmoTRX

15 Jan 2018

Hi people,

I'm doing some tests with an old USRP2 + RF900 daughterboard, and I get
a very unstable system (with osmo-trx, osmo-bts-trx and osmo-nitb) -
some phones can connect, some dont. I'd like to identify the problem -
is it a bad GBit ethernet or the lack of a GPSDO connected to the 10MHz
port?

Here is osmo-trx startup:
+++
Info: SSE3 support compiled in and supported by CPU
Info: SSE4.1 support compiled in and supported by CPU
Config Settings
   Log Level............... DEBUG
   Device args.............
   TRX Base Port........... 5700
   TRX Address............. 127.0.0.1
   GSM Core 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
   Tuning offset........... 0
   RSSI to dBm offset...... 0
   Swap channels........... 0

INFO 140529100949376 09:57:53.4 UHDDevice.cpp:623:open: Using discovered
UHD device type=usrp2,addr=192.168.10.2,name=,serial=024N11043
-- Opening a USRP2/N-Series device...
-- Current recv frame size: 1472 bytes
-- Current send frame size: 1472 bytes
INFO 140529100949376 09:57:54.6 UHDDevice.cpp:441:set_rates: Rates
configured for N2XX 4/1 Tx/Rx SPS
INFO 140529100949376 09:57:54.7 UHDDevice.cpp:401:init_gains: Supported
Tx gain range [0; 0]
INFO 140529100949376 09:57:54.7 UHDDevice.cpp:406:init_gains: Supported
Rx gain range [0; 70]
INFO 140529100949376 09:57:54.7 UHDDevice.cpp:410:init_gains: Default
setting Tx gain for channel 0 to 0
INFO 140529100949376 09:57:54.7 UHDDevice.cpp:417:init_gains: Default
setting Rx gain for channel 0 to 35
INFO 140529100949376 09:57:54.7 UHDDevice.cpp:712:open:
Single USRP:
  Device: USRP2 / N-Series Device
  Mboard 0: USRP2 r4
  RX Channel: 0
    RX DSP: 0
    RX Dboard: A
    RX Subdev: RFX900 RX
  TX Channel: 0
    TX DSP: 0
    TX Dboard: A
    TX Subdev: RFX900 TX
-- Transceiver active with 1 channel(s)
+++

From osmo-trx I get millions of messages:
+++
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.1
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 7:1142324
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 0:1142349
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 0:1142325
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 1:1142349
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 2:1142349
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 3:1142349
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 4:1142349
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 5:1142349
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 6:1142349
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 7:1142349
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 2:1142325
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 4:1142325
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 6:1142325
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 7:1142325
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 0:1142350
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 0:1142326
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 1:1142350
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 2:1142350
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 3:1142350
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 4:1142350
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 5:1142350
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 6:1142350
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 7:1142350
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 2:1142326
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 4:1142326
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 6:1142326
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 7:1142326
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 0:1142351
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 0:1142327
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 1:1142351
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 2:1142351
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 3:1142351
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 4:1142351
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 5:1142351
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 6:1142351
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 7:1142351
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 3:1142327
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 4:1142327
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 6:1142327
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 7:1142327
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 0:1142352
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 1:1142352
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 0:1142328
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 2:1142352
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 3:1142352
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 4:1142352
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 5:1142352
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 6:1142352
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 7:1142352
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 3:1142328
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 4:1142328
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 6:1142328
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 7:1142328
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 0:1142353
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 1:1142353
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 0:1142329
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 2:1142353
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 3:1142353
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 4:1142353
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 5:1142353
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 6:1142353
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 7:1142353
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 3:1142329
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 4:1142329
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 6:1142329
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 7:1142329
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 0:1142354
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 1:1142354
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 2:1142354
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 3:1142354
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 0:1142330
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 4:1142354
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 5:1142354
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 6:1142354
DEBUG 140529099826944 10:02:27.4
Transceiver.cpp:852:driveTxPriorityQueue: rcvd. burst at: 7:1142354
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 3:1142330
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 4:1142330
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:946:readSamples: Received
timestamp = 32693.2
DEBUG 140529101072128 10:02:27.4 Transceiver.cpp:960:driveTxFIFO: radio
clock 6:1142330
DEBUG 140529101039360 10:02:27.4 UHDDevice.cpp:902:readSamples:
Requested timestamp = 32693.2
+++

and from osmo-bts-trx:
+++
 (bts=0,trx=0,ts=0) : PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127677/850/05/16/45 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127677/850/05/16/45 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127683/850/11/22/03 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127683/850/11/22/03 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:480 1127714/850/16/02/34 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1127714/850/16/02/34 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1127718/850/20/06/38 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127718/850/20/06/38 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127724/850/00/12/44 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127724/850/00/12/44 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127728/850/04/16/48 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127728/850/04/16/48 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127734/850/10/22/02 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127734/850/10/22/02 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:480 1127754/850/04/42/22 (bts=0,trx=0,ts=0)
SACCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x40
<0007> scheduler.c:418 1127754/850/04/42/22 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x40
<0007> scheduler.c:480 1127765/850/15/02/33 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1127765/850/15/02/33 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1127769/850/19/06/37 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127769/850/19/06/37 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0006> scheduler_trx.c:1619 TRX Clock Ind: elapsed_us= 997110,
elapsed_fn=217, error_us=-4345
<0006> scheduler_trx.c:1637 GSM clock jitter: 3322 us (elapsed_fn=1)
<0007> scheduler.c:480 1127775/850/25/12/43 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127775/850/25/12/43 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0006> scheduler_trx.c:1660 We were 1 FN slower than TRX, compensated
<0007> scheduler.c:480 1127779/850/03/16/47 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127779/850/03/16/47 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127785/850/09/22/01 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127785/850/09/22/01 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:480 1127816/850/14/02/32 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1127816/850/14/02/32 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1127820/850/18/06/36 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127820/850/18/06/36 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127826/850/24/12/42 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127826/850/24/12/42 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127830/850/02/16/46 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127830/850/02/16/46 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127836/850/08/22/00 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127836/850/08/22/00 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:480 1127856/850/02/42/20 (bts=0,trx=0,ts=0)
SACCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x40
<0007> scheduler.c:418 1127856/850/02/42/20 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x40
<0007> scheduler.c:480 1127867/850/13/02/31 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1127867/850/13/02/31 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1127871/850/17/06/35 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127871/850/17/06/35 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127877/850/23/12/41 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127877/850/23/12/41 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127881/850/01/16/45 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127881/850/01/16/45 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127887/850/07/22/51 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127887/850/07/22/51 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:480 1127918/850/12/02/30 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1127918/850/12/02/30 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1127922/850/16/06/34 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127922/850/16/06/34 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127928/850/22/12/40 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127928/850/22/12/40 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127932/850/00/16/44 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127932/850/00/16/44 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127938/850/06/22/02 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127938/850/06/22/02 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:480 1127958/850/00/42/22 (bts=0,trx=0,ts=0)
SACCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x40
<0007> scheduler.c:418 1127958/850/00/42/22 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x40
<0007> scheduler.c:480 1127969/850/11/02/33 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1127969/850/11/02/33 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1127973/850/15/06/37 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127973/850/15/06/37 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127979/850/21/12/43 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127979/850/21/12/43 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127983/850/25/16/47 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1127983/850/25/16/47 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:480 1127989/850/05/22/01 (bts=0,trx=0,ts=0)
SDCCH/4(0): PH-RTS.ind: chan_nr=0x20 link_id=0x00
<0007> scheduler.c:418 1127989/850/05/22/01 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x20 link_id=0x00
<0006> scheduler_trx.c:1619 TRX Clock Ind: elapsed_us= 998173,
elapsed_fn=216, error_us=+1333
<0006> scheduler_trx.c:1637 GSM clock jitter: -2537 us (elapsed_fn=0)
<0000> rsl.c:2458 (bts=0,trx=0,ts=0,ss=0) Rx RSL DEACTIVATE_SACCH
<0006> l1sap.c:1394 deactivating sacch chan_nr=0x20 trx=0
<0006> scheduler.c:1383 Deactivating SACCH/4(0) on trx=0 ts=0
<0000> rsl.c:2458 (bts=0,trx=0,ts=0,ss=0) Rx RSL RF_CHAN_REL
<0006> l1sap.c:1378 deactivating channel chan_nr=0x20 trx=0
<0006> scheduler.c:1383 Deactivating SDCCH/4(0) on trx=0 ts=0
<0006> l1sap.c:602 deactivate confirm chan_nr=0x20 trx=0
<0000> rsl.c:580 (bts=0,trx=0,ts=0,ss=0) Tx RF CHAN REL ACK
<0007> scheduler.c:480 1128020/850/10/02/32 (bts=0,trx=0,ts=0) BCCH:
PH-RTS.ind: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:418 1128020/850/10/02/32 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0x80 link_id=0x00
<0007> scheduler.c:480 1128024/850/14/06/36 (bts=0,trx=0,ts=0) CCCH:
PH-RTS.ind: chan_nr=0x90 link_id=0x00
<0007> scheduler.c:418 1128024/850/14/06/36 (bts=0,trx=0,ts=0) :
PH-DATA.req: chan_nr=0
+++

From NITB:
+++
Mon Jan 15 10:04:11 2018 <0005> abis_nm.c:507 BTS0 feature 'EGPRS'
reported via OML does not match statically set feature: 0 != 1. Please fix.
Mon Jan 15 10:04:11 2018 <0005> abis_nm.c:507 BTS0 feature 'OML Alerts'
reported via OML does not match statically set feature: 1 != 0. Please fix.
Mon Jan 15 10:04:11 2018 <0005> abis_nm.c:573 BTS0: ARI reported
sw[0/2]: sysmobts is 0.7.0.20180114
Mon Jan 15 10:04:11 2018 <0005> abis_nm.c:446 BTS0 reported variant:
omso-bts-trx
Mon Jan 15 10:04:11 2018 <0005> abis_nm.c:468 BTS0 Attribute
Manufacturer Dependent State is unreported
Mon Jan 15 10:04:11 2018 <0005> abis_nm.c:573 BTS0: ARI reported
sw[0/1]: TRX_PHY_VERSION is Unknown
+++

And after some time, osmo-trx stops reading samples from UHD and
osmo-bts-trx shuts down.

Thanks,
Rafael Diniz

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

USRP2 and OsmoTRX