Project

General

Profile

Actions

Bug #3346

closed

osmo-trx-lms: Multi channel support: "R_CTL_LPF range limit reached"

Added by laforge almost 6 years ago. Updated about 4 years ago.

Status:
Rejected
Priority:
Normal
Assignee:
Category:
LimeSDR
Target version:
-
Start date:
06/13/2018
Due date:
% Done:

0%

Spec Reference:

Description

When trying to use two channels on a LimeSDR, I'm getting the following:

Wed Jun 13 22:07:07 2018 DMAIN <0000> LMSDevice.cpp:188 [tid=139802759618432] Setting LPFBW chan 0
Wed Jun 13 22:07:07 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 10 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU Ref. clock: 30.72 MHz
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 166 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] RX LPF configured
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] INT 75, FRAC 0, DIV_OUTCH_CGEN 18
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] VCO 2334.72 MHz, RefClk 30.72 MHz
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] csw 154; interval [151, 157]
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU Ref. clock: 30.72 MHz
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 71 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] Filter calibrated. Filter order-4th, filter bandwidth set to 5.2 MHz.Real pole 1st order filter set to 2.5 MHz. Preemphasis filter not active
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] INT 75, FRAC 0, DIV_OUTCH_CGEN 18
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] VCO 2334.72 MHz, RefClk 30.72 MHz
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] csw 154; interval [151, 157]
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] TX LPF configured
Wed Jun 13 22:07:08 2018 DMAIN <0000> LMSDevice.cpp:193 [tid=139802759618432] Calibrating chan 0
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 110 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] Rx calibration finished
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 243 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] Tx calibration finished
Wed Jun 13 22:07:08 2018 DMAIN <0000> LMSDevice.cpp:188 [tid=139802759618432] Setting LPFBW chan 1
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU Ref. clock: 30.72 MHz
Wed Jun 13 22:07:08 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 0 ms
Wed Jun 13 22:07:09 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] MCU algorithm time: 279 ms
Wed Jun 13 22:07:09 2018 DLMS <0001> LMSDevice.cpp:76 [tid=139802759618432] Tune Rx Filter: MCU error 12 (Rx R_CTL_LPF range limit reached)
Wed Jun 13 22:07:09 2018 DMAIN <0000> LMSDevice.cpp:207 [tid=139802759618432] Error in LMS open, closing: 
Wed Jun 13 22:07:09 2018 DMAIN <0000> osmo-trx.cpp:447 [tid=139802759618432] Failed to create radio device
Shutting down transceiver...

So as one can see, the first RX filter can be tuned as expected, but the second filter for the second channel then fails. I don't understand enough about the LimeSDR to debug this further. Interestingly, google returns exactly zero related hits when searching for that error message, as do the LimeSuite github and the myriadrf forum.

Config file looks like this:

trx
 bind-ip 127.0.0.1
 remote-ip 127.0.0.1
 base-port 5700
 egprs disable
 tx-sps 4
 rx-sps 4
 chan 0
  tx-path BAND1
  rx-path LNAW
 chan 1
  tx-path BAND2
  rx-path LNAH

It doesn't really matter which tx-path / rx-path we are setting in chan 1, it will fail with the same error.


Related issues

Related to OsmoTRX - Feature #2919: Native LimeSDR supportResolvedpespin02/09/2018

Actions
Related to OsmoGSMTester - Bug #4151: osmo-gsm-tester: osmo-trx-lms process sometimes kept forever in zombie-alike state after killing itStalled08/14/2019

Actions
Related to OsmoTRX - Bug #4207: multi-arfcn not working properlyResolvedpespin09/16/2019

Actions
Related to OsmoTRX - Feature #4362: osmo-trx-lms: Support multi-arfcn featureStalled01/13/2020

Actions
Actions #1

Updated by laforge almost 6 years ago

Actions #2

Updated by laforge over 5 years ago

  • Assignee set to roh

Should be fixed by this commit:

https://github.com/myriadrf/LimeSuite/commit/40824fd1e1aaf65c436cd372ff5dbbe2990704f1

roh please test against a recent LimeSuite (including above commit) using the config file snippet I posted above. If it does start up, it appears to be fixed. If it doens't start and spits the "R_CTL_LPF range limit reached" error, the bug still persists.

Actions #3

Updated by laforge over 5 years ago

  • Assignee changed from roh to pespin

the same bug can no longer be reproduced. However, osmo-trx-lms has a hard-coded check if channel == 0 and will fail on any other channel, so there's now a hard-coded limitation in osmo-trx-lms and not a problem of LimeSuite anymore.

Actions #4

Updated by pespin over 5 years ago

I submitted several patches to gerrit that remove several wrong conditions and improve the logging when running with several chans.

However, I still get this LMS error message during TRX setup (but it doesn't seem it returns an error code):

Tue Dec  4 14:24:19 2018 DDEV <0001> LMSDevice.cpp:200 [tid=140630265782784] Calibrating chan 1
Tue Dec  4 14:24:19 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630265782784] Rx calibration: MCU error 5 (Loopback signal weak: not connected/insufficient gain?)
Tue Dec  4 14:24:19 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630265782784] Tx Calibration: MCU error 5 (Loopback signal weak: not connected/insufficient gain?)

Here's the cfg file I use with my limeSDR-USB: (network configured to use 2 TRX and tested with other devices):

log stderr
  logging filter all 1
  logging color 1
  logging print category 1
  logging timestamp 1
  logging print file basename
  logging level set-all info
!
line vty
 no login
!
trx
 bind-ip 192.168.30.100
 remote-ip 192.168.30.1
 base-port 5700
 egprs disable
 clock-ref internal
 tx-sps 4
 rx-sps 4
 rt-prio 18
 chan 0
  tx-path BAND1
  rx-path LNAW
 chan 1
  tx-path BAND1
  rx-path LNAW

Immediatelly after starting osmo-bts-trx, osmo-trx starts failing. It seems it's already reading samples before it is fully configured through the CTRL iface:

Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:222 [tid=140630226585344] starting LMS...
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:330 [tid=140630226585344] chan 0: Setting TX gain to 36.5 dB
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:345 [tid=140630226585344] chan 0: Setting RX gain to 36.5 dB
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:330 [tid=140630226585344] chan 1: Setting TX gain to 36.5 dB
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:345 [tid=140630226585344] chan 1: Setting RX gain to 36.5 dB
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:392 [tid=140630226585344] Initial timestamp 27500
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:198 [tid=140630226585344] Radio started
Tue Dec  4 14:24:21 2018 DMAIN <0000> Threads.cpp:116 [tid=140630223623936] Thread 140630223623936 (task 26965) set name: TxLower
Tue Dec  4 14:24:21 2018 DMAIN <0000> Threads.cpp:116 [tid=140630223529728] Thread 140630223529728 (task 26966) set name: RxLower
Tue Dec  4 14:24:21 2018 DMAIN <0000> Threads.cpp:116 [tid=140630223369984] Thread 140630223369984 (task 26967) set name: RxUpper0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Threads.cpp:116 [tid=140630222173952] Thread 140630222173952 (task 26968) set name: TxUpper0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Threads.cpp:116 [tid=140630222046976] Thread 140630222046976 (task 26969) set name: RxUpper1
Tue Dec  4 14:24:21 2018 DMAIN <0000> Threads.cpp:116 [tid=140630221920000] Thread 140630221920000 (task 26970) set name: TxUpper1
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETRXGAIN 1
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:345 [tid=140630226585344] chan 0: Setting RX gain to 1 dB
Tue Dec  4 14:24:21 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630162028288] L
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 0 (0) diff=ffffffffffff9494
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETPOWER 0
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:330 [tid=140630226585344] chan 0: Setting TX gain to 73 dB
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 0 4
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 1 1
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got 7530 (7530) diff=9c4
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 2 1
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 9c4 (9c4) diff=ffffffffffff9e58
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 3 1
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 4 1
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 5 1
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 6 13
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got 7ef4 (7ef4) diff=1388
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 1388 (1388) diff=ffffffffffffa81c
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got 88b8 (88b8) diff=1d4c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 1d4c (1d4c) diff=ffffffffffffb1e0
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got 927c (927c) diff=2710
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 2710 (2710) diff=ffffffffffffbba4
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got 9c40 (9c40) diff=30d4
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 30d4 (30d4) diff=ffffffffffffc568
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got a604 (a604) diff=3a98
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 3a98 (3a98) diff=ffffffffffffcf2c
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226585344] chan 0: command is SETSLOT 7 13
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got afc8 (afc8) diff=445c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 445c (445c) diff=ffffffffffffd8f0
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got b98c (b98c) diff=4e20
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 4e20 (4e20) diff=ffffffffffffe2b4
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got c350 (c350) diff=57e4
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 57e4 (57e4) diff=ffffffffffffec78
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got cd14 (cd14) diff=61a8
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 1 recv buffer of len 2500 expect 6b6c got 61a8 (61a8) diff=fffffffffffff63c
Tue Dec  4 14:24:21 2018 DMAIN <0000> radioInterface.cpp:330 [tid=140630223529728] Receive error 0
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:907 [tid=140630223529728] radio Interface receive failed, requesting stop.
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c got d6d8 (d6d8) diff=6b6c
Tue Dec  4 14:24:21 2018 DMAIN <0000> Transceiver.cpp:1038 [tid=140630223529728] ClockInterface: sending IND CLOCK 2665236
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 7530 got e09c (e09c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 7ef4 got ea60 (ea60) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 88b8 got f424 (f424) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 927c got fde8 (fde8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 9c40 got 107ac (107ac) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect a604 got 11170 (11170) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect afc8 got 11b34 (11b34) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect b98c got 124f8 (124f8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect c350 got 12ebc (12ebc) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect cd14 got 13880 (13880) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect d6d8 got 14244 (14244) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect e09c got 14c08 (14c08) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect ea60 got 155cc (155cc) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect f424 got 15f90 (15f90) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect fde8 got 16954 (16954) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 107ac got 17318 (17318) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 11170 got 17cdc (17cdc) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 11b34 got 186a0 (186a0) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 124f8 got 19064 (19064) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 12ebc got 19a28 (19a28) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 13880 got 1a3ec (1a3ec) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 14244 got 1adb0 (1adb0) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 14c08 got 1b774 (1b774) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 155cc got 1c138 (1c138) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 15f90 got 1cafc (1cafc) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 16954 got 1d4c0 (1d4c0) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 17318 got 1de84 (1de84) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 17cdc got 1e848 (1e848) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 186a0 got 1f20c (1f20c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 19064 got 1fbd0 (1fbd0) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 19a28 got 20594 (20594) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1a3ec got 20f58 (20f58) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1adb0 got 2191c (2191c) diff=6b6c
Tue Dec  4 14:24:21 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630162028288] L
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1b774 got 222e0 (222e0) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1c138 got 22ca4 (22ca4) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1cafc got 23668 (23668) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1d4c0 got 2402c (2402c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1de84 got 249f0 (249f0) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1e848 got 253b4 (253b4) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1f20c got 25d78 (25d78) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 1fbd0 got 2673c (2673c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 20594 got 27100 (27100) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 20f58 got 27ac4 (27ac4) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2191c got 28488 (28488) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 222e0 got 28e4c (28e4c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 22ca4 got 29810 (29810) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 23668 got 2a1d4 (2a1d4) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2402c got 2ab98 (2ab98) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 249f0 got 2b55c (2b55c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 253b4 got 2bf20 (2bf20) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 25d78 got 2c8e4 (2c8e4) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2673c got 2d2a8 (2d2a8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 27100 got 2dc6c (2dc6c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 27ac4 got 2e630 (2e630) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 28488 got 2eff4 (2eff4) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 28e4c got 2f9b8 (2f9b8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 29810 got 3037c (3037c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2a1d4 got 30d40 (30d40) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2ab98 got 31704 (31704) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2b55c got 320c8 (320c8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2bf20 got 32a8c (32a8c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2c8e4 got 33450 (33450) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2d2a8 got 33e14 (33e14) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2dc6c got 347d8 (347d8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2e630 got 3519c (3519c) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2eff4 got 35b60 (35b60) diff=6b6c
Tue Dec  4 14:24:21 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630162028288] L
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 2f9b8 got 36524 (36524) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3037c got 36ee8 (36ee8) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 30d40 got 378ac (378ac) diff=6b6c
Tue Dec  4 14:24:21 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 31704 got 38270 (38270) diff=6b6c
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is RXTUNE 1782400
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:616 [tid=140630226458368] chan 1: Setting Rx Freq to 1.7824e+09 Hz
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 320c8 got 38c34 (38c34) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 32a8c got 395f8 (395f8) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 33450 got 39fbc (39fbc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 33e14 got 3a980 (3a980) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 347d8 got 3b344 (3b344) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3519c got 3bd08 (3bd08) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 35b60 got 3c6cc (3c6cc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 36524 got 3d090 (3d090) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 36ee8 got 3da54 (3da54) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 378ac got 3e418 (3e418) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 38270 got 3eddc (3eddc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 38c34 got 3f7a0 (3f7a0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 395f8 got 40164 (40164) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 39fbc got 40b28 (40b28) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3a980 got 414ec (414ec) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3b344 got 41eb0 (41eb0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3bd08 got 42874 (42874) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3c6cc got 43238 (43238) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3d090 got 43bfc (43bfc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3da54 got 445c0 (445c0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3e418 got 44f84 (44f84) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3eddc got 45948 (45948) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 3f7a0 got 4630c (4630c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 40164 got 46cd0 (46cd0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 40b28 got 47694 (47694) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 414ec got 48058 (48058) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 41eb0 got 48a1c (48a1c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 42874 got 493e0 (493e0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 43238 got 49da4 (49da4) diff=6b6c
Tue Dec  4 14:24:22 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630162028288] L
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 43bfc got 4a768 (4a768) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 445c0 got 4b12c (4b12c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 44f84 got 4baf0 (4baf0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 45948 got 4c4b4 (4c4b4) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4630c got 4ce78 (4ce78) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 46cd0 got 4d83c (4d83c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 47694 got 4e200 (4e200) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 48058 got 4ebc4 (4ebc4) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 48a1c got 4f588 (4f588) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 493e0 got 4ff4c (4ff4c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 49da4 got 50910 (50910) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4a768 got 512d4 (512d4) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4b12c got 51c98 (51c98) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4baf0 got 5265c (5265c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4c4b4 got 53020 (53020) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4ce78 got 539e4 (539e4) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4d83c got 543a8 (543a8) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4e200 got 54d6c (54d6c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4ebc4 got 55730 (55730) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4f588 got 560f4 (560f4) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 4ff4c got 56ab8 (56ab8) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 50910 got 5747c (5747c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 512d4 got 57e40 (57e40) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 51c98 got 58804 (58804) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5265c got 591c8 (591c8) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 53020 got 59b8c (59b8c) diff=6b6c
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is TXTUNE 1877400
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:604 [tid=140630226458368] chan 1: Setting Tx Freq to 1.8774e+09 Hz
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 539e4 got 5a550 (5a550) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 543a8 got 5af14 (5af14) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 54d6c got 5b8d8 (5b8d8) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 55730 got 5c29c (5c29c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 560f4 got 5cc60 (5cc60) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 56ab8 got 5d624 (5d624) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5747c got 5dfe8 (5dfe8) diff=6b6c
Tue Dec  4 14:24:22 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630162028288] L
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 57e40 got 5e9ac (5e9ac) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 58804 got 5f370 (5f370) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 591c8 got 5fd34 (5fd34) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 59b8c got 606f8 (606f8) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5a550 got 610bc (610bc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5af14 got 61a80 (61a80) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5b8d8 got 62444 (62444) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5c29c got 62e08 (62e08) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5cc60 got 637cc (637cc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5d624 got 64190 (64190) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5dfe8 got 64b54 (64b54) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5e9ac got 65518 (65518) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5f370 got 65edc (65edc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 5fd34 got 668a0 (668a0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 606f8 got 67264 (67264) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 610bc got 67c28 (67c28) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 61a80 got 685ec (685ec) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 62444 got 68fb0 (68fb0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 62e08 got 69974 (69974) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 637cc got 6a338 (6a338) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 64190 got 6acfc (6acfc) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 64b54 got 6b6c0 (6b6c0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 65518 got 6c084 (6c084) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 65edc got 6ca48 (6ca48) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 668a0 got 6d40c (6d40c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 67264 got 6ddd0 (6ddd0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 67c28 got 6e794 (6e794) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 685ec got 6f158 (6f158) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 68fb0 got 6fb1c (6fb1c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 69974 got 704e0 (704e0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6a338 got 70ea4 (70ea4) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6acfc got 71868 (71868) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6b6c0 got 7222c (7222c) diff=6b6c
Tue Dec  4 14:24:22 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630162028288] L
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6c084 got 72bf0 (72bf0) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6ca48 got 735b4 (735b4) diff=6b6c
Tue Dec  4 14:24:22 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630226458368] SetNCOFrequency(index = 0) - Frequency(0.4 MHz) out of range [0-0.270833) MHz
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6d40c got 73f78 (73f78) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6ddd0 got 7493c (7493c) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6e794 got 75300 (75300) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6f158 got 75cc4 (75cc4) diff=6b6c
Tue Dec  4 14:24:22 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630226458368] SetNCOFrequency(index = 0) - Frequency(0.4 MHz) out of range [0-0.270833) MHz
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 6fb1c got 76688 (76688) diff=6b6c
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 704e0 got 7704c (7704c) diff=6b6c
Tue Dec  4 14:24:22 2018 DMAIN <0000> osmo-trx.cpp:449 [tid=140630265782784] Shutting down transceiver...
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:307 [tid=140630265782784] Stopping the transceiver
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:530 [tid=140630223529728] chan 0 recv buffer of len 2500 expect 70ea4 got 77a10 (77a10) diff=6b6c
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:320 [tid=140630265782784] Stopping the device
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETTSC 7
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:826 [tid=140630226458368] Changing TSC from 7 to 7
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETRXGAIN 1
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:345 [tid=140630226458368] chan 1: Setting RX gain to 1 dB
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETPOWER 0
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:330 [tid=140630226458368] chan 1: Setting TX gain to 73 dB
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 0 1
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 1 7
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 2 1
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 3 1
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 4 1
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 5 1
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 6 13
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:724 [tid=140630226458368] chan 1: command is SETSLOT 7 13
Tue Dec  4 14:24:22 2018 DLMS <0002> LMSDevice.cpp:83 [tid=140630153635584] popping from TX, samples popped 230/510
Tue Dec  4 14:24:22 2018 DMAIN <0000> Transceiver.cpp:333 [tid=140630265782784] Transceiver stopped
Tue Dec  4 14:24:22 2018 DDEV <0001> LMSDevice.cpp:62 [tid=140630265782784] Closing LMS device

Actions #5

Updated by pespin over 5 years ago

Actions #6

Updated by pespin over 5 years ago

  • Subject changed from osmo-trx-lms "R_CTL_LPF range limit reached" to osmo-trx-lms: Multi channel support: "R_CTL_LPF range limit reached"
Actions #7

Updated by pespin almost 5 years ago

  • Status changed from New to In Progress

I'm retaking this task after all previous improvements in osmo-trx-lms (smpl_buf, rate_ctr, roh's re-arranging of API calls, etc.)

Running with latest LimeSuite (19.04 or around).

Using following cfg in osmo-bts-trx:

phy 0
 osmotrx ip local 192.168.30.1
 osmotrx ip remote 192.168.30.100
 instance 0
   osmotrx rx-gain 1
 instance 1
   osmotrx rx-gain 1
...
 trx 0
  phy 0 instance 0
 trx 1
  phy 0 instance 1

And in osmo-trx-lms.cfg (may need to use BAND2 there?):

 chan 0
  tx-path BAND1
  rx-path LNAW
 chan 1
  tx-path BAND1
  rx-path LNAW

When BTS tries to set ARFCNs (870 and 872 in DCS1800):

SUMMARY: AddressSanitizer: 12 byte(s) leaked in 1 allocation(s).
Tue Jun  4 18:11:28 2019 DLMS <0003> LMSDevice.cpp:92 [tid=139703091786240] RemoteControl Listening on port: 5000
Tue Jun  4 18:11:29 2019 DLMS <0003> LMSDevice.cpp:92 [tid=139703091786240] Reference clock 30.72 MHz
Tue Jun  4 18:11:29 2019 DDEV <0002> LMSDevice.cpp:190 [tid=139703091786240] Init LMS device
Tue Jun  4 18:11:29 2019 DDEV <0002> LMSDevice.cpp:203 [tid=139703091786240] Setting Internal clock reference
Tue Jun  4 18:11:29 2019 DLMS <0003> LMSDevice.cpp:92 [tid=139703091786240] Disabling external reference clock
Tue Jun  4 18:11:29 2019 DDEV <0002> LMSDevice.cpp:97 [tid=139703091786240] Sample Rate: Min=100000 Max=6.144e+07 Step=1.97626e-323
Tue Jun  4 18:11:29 2019 DDEV <0002> LMSDevice.cpp:226 [tid=139703091786240] Setting sample rate to 1.08333e+06 4
Tue Jun  4 18:11:29 2019 DDEV <0002> LMSDevice.cpp:232 [tid=139703091786240] Sample Rate: Host=1.08333e+06 RF=3.46667e+07
Tue Jun  4 18:11:29 2019 DMAIN <0000> LMSDevice.cpp:209 [tid=139703091786240] Antennas configured successfully
Tue Jun  4 18:11:29 2019 DMAIN <0000> Threads.cpp:116 [tid=139703052617472] Thread 139703052617472 (task 26528) set name: CtrlService0
Tue Jun  4 18:11:29 2019 DMAIN <0000> Threads.cpp:116 [tid=139703052490496] Thread 139703052490496 (task 26529) set name: CtrlService1
Tue Jun  4 18:11:29 2019 DMAIN <0000> osmo-trx.cpp:526 [tid=139703091786240] -- Transceiver active with 2 channel(s)
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:717 [tid=139703052617472][chan=0] command is 'POWEROFF'
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:848 [tid=139703052617472][chan=0] response is 'RSP POWEROFF 0'
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:717 [tid=139703052617472][chan=0] command is 'RXTUNE 1781800'
Tue Jun  4 18:11:30 2019 DDEV <0002> LMSDevice.cpp:763 [tid=139703052617472][chan=0] Setting Rx Freq to 1.7818e+09 Hz
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:717 [tid=139703052490496][chan=1] command is 'RXTUNE 1782200'
Tue Jun  4 18:11:30 2019 DDEV <0002> LMSDevice.cpp:763 [tid=139703052490496][chan=1] Setting Rx Freq to 1.7822e+09 Hz
Tue Jun  4 18:11:30 2019 DLMS <0003> LMSDevice.cpp:92 [tid=139703052617472] SetFrequencySXR(1781.8 MHz) - cannot deliver frequency
Tue Jun  4 18:11:30 2019 DDEV <0002> LMSDevice.cpp:766 [tid=139703052617472][chan=0] Error setting Rx Freq to 1.7818e+09 Hz
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:796 [tid=139703052617472] RX failed to tune
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:848 [tid=139703052617472][chan=0] response is 'RSP RXTUNE 1 1781800'
Tue Jun  4 18:11:30 2019 DTRXCTRL <0001> Transceiver.cpp:848 [tid=139703052490496][chan=1] response is 'RSP RXTUNE 0 1782200'

Notice how chan=0 and chan=1 calls/returns are interleaved due to each chan running its CTRL socket on a different thread. We actually need to make sure the API is thread-safe.

Actions #8

Updated by pespin over 4 years ago

I disabled for now multitrx tests in osmo-gsm-tester since this feature doesn't work yet and those test seem to create problems with the other ones, see #4151.

Actions #9

Updated by pespin over 4 years ago

  • Related to Bug #4151: osmo-gsm-tester: osmo-trx-lms process sometimes kept forever in zombie-alike state after killing it added
Actions #10

Updated by pespin about 4 years ago

  • Related to Bug #4207: multi-arfcn not working properly added
Actions #11

Updated by pespin about 4 years ago

  • Related to Feature #4362: osmo-trx-lms: Support multi-arfcn feature added
Actions #12

Updated by pespin about 4 years ago

According to [1]:

channels A and B uses the same PLL clock so different frequencies can only be achieved by shifting digitally (using NCO). Digital shift is limited by RF bandwidth that is being sampled.

channels A and B are really only useful for MIMO and not for anything else, such as operating independend receivers/transmitters on different frequencies.

So we cannot support multi-channel with osmo-trx-lms. I'm opening a separate ticket regarding multi-arfcn support. Let's do that in #4362

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

Actions #13

Updated by pespin about 4 years ago

  • Status changed from In Progress to Rejected
Actions

Also available in: Atom PDF

Add picture from clipboard (Maximum size: 48.8 MB)