Project

General

Profile

Actions
Copy link

Bug #2412

closed

osmo-bts-litecell15 RTP clock drift

Added by pespin over 6 years ago. Updated about 6 years ago.

Status:
Resolved
Priority:
Normal
Assignee:
pespin
Category:
osmo-bts-litecell15
Target version:
-
Start date:
07/31/2017
Due date:
% Done:

100%

Spec Reference:

Description

After patches from #2335, it seems some PH-DATA.ind events are still missed on litecell15 (running osmo-bts 82987f7a7dd35451b75326b34e36c8f83289f6f6) during a call with "dtx downlink" and "dtx uplink force". Some error lines describing RTP clock drift can be seen, such as:

Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:95 RTP clock out of sync with lower layer: 640 vs 160 (1488790->1488807)

Attached below a more extensive log trace during the call:

Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:462 PH-DATA.req(15 06 21 00 01 f0 2b 2b 2b 2b 2b 2b 2b 2b
 2b 2b 2b 2b 2b 2b 2b 2b 2b )
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488809
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488810
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488811
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488811/1122/25/19/15 chan_nr=0x0b link_id=0x40d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/25/19 chan_nr=0b link_id=0x40
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:462 PH-DATA.req(0e 00 03 03 06 1e 00 00 01 f0 02 00 17 97
 ff 2b 2b 2b 2b 2b 2b 2b 2b )
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488812
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488812/1122/00/20/16 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/00/20 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488812/1122/00/20/16 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/00/20 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488812/1122/00/20/16 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/00/20 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488812/1122/00/20/16 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=3,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/00/20 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488813
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:843 Rx PH-RTS.ind 1122/01/21 SAPI=SCH
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488814
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488815
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488816
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488816/1122/04/24/20 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/04/24 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488816/1122/04/24/20 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/04/24 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488816/1122/04/24/20 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/04/24 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488816/1122/04/24/20 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=3,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/04/24 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488817
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488818
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488819
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488820
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488820/1122/08/28/24 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/08/28 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488820/1122/08/28/24 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/08/28 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488820/1122/08/28/24 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/08/28 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488820/1122/08/28/24 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=3,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/08/28 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488821
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:475 MPH_INFO meas ind chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:953 Rx PH-DATA.ind TCH/F 1488807/1122/21/15/11 (hL2 00030
0bb): 01 d6 a9 9c 61 92 00 00 00 00 04 90 00 00 00 02 40 00 02 41 00 00 00 00 20 80 01 00 00 10 42 00 92 80
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:136 (bts=0,trx=0,ts=3,ss=0) Rx -> RTP: d6 a9 9c 61 92 00 00 00 00 04 9
0 00 00 00 02 40 00 02 41 00 00 00 00 20 80 01 00 00 10 42 00 92 80
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:1062 Rx TCH.ind 1488807/1122/21/15/11 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:95 RTP clock out of sync with lower layer: 640 vs 160 (1488790->1488807)
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488822
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488823
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:843 Rx PH-RTS.ind 1122/11/31 SAPI=SCH
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488824
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488825
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488825/1122/13/33/29 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/13/33 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488825/1122/13/33/29 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/13/33 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488825/1122/13/33/29 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/13/33 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488825/1122/13/33/29 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=3,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/13/33 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-RA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:475 MPH_INFO meas ind chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:953 Rx PH-DATA.ind TCH/F 1488812/1122/00/20/16 (hL2 00020
0bb): 01 d6 67 a4 21 99 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:136 (bts=0,trx=0,ts=2,ss=0) Rx -> RTP: d6 67 a4 21 99 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:1062 Rx TCH.ind 1488812/1122/00/20/16 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:95 RTP clock out of sync with lower layer: 1600 vs 160 (1488768->1488812)
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:475 MPH_INFO meas ind chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:953 Rx PH-DATA.ind TCH/F 1488812/1122/00/20/16 (hL2 00030
0bb): 01 d6 e9 a4 25 92 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:136 (bts=0,trx=0,ts=3,ss=0) Rx -> RTP: d6 e9 a4 25 92 00 00 00 00 00 0
0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:1062 Rx TCH.ind 1488812/1122/00/20/16 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488826
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488827
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488828
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488829
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488829/1122/17/37/33 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/17/37 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488829/1122/17/37/33 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/17/37 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488829/1122/17/37/33 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/17/37 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488829/1122/17/37/33 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/17/37 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:475 MPH_INFO meas ind chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:953 Rx PH-DATA.ind TCH/F 1488816/1122/04/24/20 (hL2 00020
0bb): 01 d6 66 ac 65 d2 00 00 00 40 00 00 00 00 00 90 00 24 90 00 00 00 02 48 00 02 48 00 00 00 01 09 24 08
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:136 (bts=0,trx=0,ts=2,ss=0) Rx -> RTP: d6 66 ac 65 d2 00 00 00 40 00 0
0 00 00 00 90 00 24 90 00 00 00 02 48 00 02 48 00 00 00 01 09 24 08
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:1062 Rx TCH.ind 1488816/1122/04/24/20 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488830
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488831
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488832
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488833
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:843 Rx PH-RTS.ind 1122/21/41 SAPI=SCH
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488833/1122/21/41/37 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/21/41 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488833/1122/21/41/37 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/21/41 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488833/1122/21/41/37 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/21/41 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488833/1122/21/41/37 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/21/41 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488834
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488835
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488836
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488837
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488838
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488838/1122/00/46/42 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/00/46 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488838/1122/00/46/42 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/00/46 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488838/1122/00/46/42 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/00/46 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488838/1122/00/46/42 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=3,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/00/46 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:475 MPH_INFO meas ind chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:953 Rx PH-DATA.ind TCH/F 1488825/1122/13/33/29 (hL2 00030
0bb): 01 d1 af 22 9b 0d 77 84 45 f2 35 a7 4d cf 4e 41 36 d9 07 4d ed 2b 42 b4 81 ed 4c ec f1 f2 c5 d8 e6 b8
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:136 (bts=0,trx=0,ts=3,ss=0) Rx -> RTP: d1 af 22 9b 0d 77 84 45 f2 35 a
7 4d cf 4e 41 36 d9 07 4d ed 2b 42 b4 81 ed 4c ec f1 f2 c5 d8 e6 b8
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:1062 Rx TCH.ind 1488825/1122/13/33/29 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:1091 Skipping RTP frame with lost payload
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:95 RTP clock out of sync with lower layer: 480 vs 160 (1488812->1488825)
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488839
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488840
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488841
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488842
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488842/1122/04/50/46 chan_nr=0x0a link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/04/50 chan_nr=0a link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488842/1122/04/50/46 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=2,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/04/50 chan_nr=0x0a
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488842/1122/04/50/46 chan_nr=0x0b link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/04/50 chan_nr=0b link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:801 Rx TCH-RTS.ind 1488842/1122/04/50/46 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:821 (bts=0,trx=0,ts=3,ss=0) DL TCH Tx queue underrun
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:855 Tx TCH.req 1122/04/50 chan_nr=0x0b
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-DATA.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488843
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488844
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:843 Rx PH-RTS.ind 1122/06/01 SAPI=SCH
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:423 MPH_INFO time ind 1488845
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:1148 Rx L1 prim PH-READY_TO_SEND.ind on queue 1
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:660 Rx PH-RTS.ind 1488845/1122/07/02/49 chan_nr=0x80 link_id=0x00d
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/common/l1sap.c:754 Tx PH-DATA.req 1122/07/02 chan_nr=80 link_id=0x00
Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0007> ../../../git/src/osmo-bts-litecell15/l1_if.c:462 PH-DATA.req(59 06 1a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff e5 04 00 )

Related issues

Related to OsmoBTS - Bug #2335: Steady increasing clock drift in RTP from source BTSClosedpespin06/21/2017

Actions
Actions
Copy link
 #1

Updated by pespin over 6 years ago

Following osmo-bts config is being used:

log stderr
  logging color 0
  logging timestamp 0
  logging level rsl info
  logging level oml info
  logging level rll notice
  logging level rr notice
  logging level meas notice
  logging level pag info
  logging level l1c info
  logging level l1p debug
  logging level dsp debug
  logging level abis notice
  logging level rtp notice
  logging level lglobal notice
  logging level llapd notice
  logging level linp notice
  logging level lmux notice
  logging level lmi notice
  logging level lmib notice
  logging level lsms notice
!
line vty
 no login
!
phy 0
 instance 0
 trx-calibration-path /mnt/rom/factory/calib
phy 1
 instance 0
 trx-calibration-path /mnt/rom/factory/calib
bts 0
! band DCS1800
 band GSM900
 ipa unit-id 1500 0
 oml remote-ip 192.168.30.1
 trx 0
  phy 0 instance 0
 trx 1
  phy 1 instance 0

Actions
Copy link
 #2

Updated by pespin over 6 years ago

  • Description updated (diff)
Actions
Copy link
 #3

Updated by pespin over 6 years ago

  • Related to Bug #2335: Steady increasing clock drift in RTP from source BTS added
Actions
Copy link
 #4

Updated by pespin over 6 years ago

Most probably the events are dropped during to the following message, which seems to happen quite a lot:

Jul 31 12:38:27 sysmobts2100 osmo-bts-lc15[2937]: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0


    if (!chan_nr) {
        LOGP(DL1C, LOGL_ERROR, "PH-DATA-INDICATION for unknown sapi " 
            "%d\n", data_ind->sapi);
        msgb_free(l1p_msg);
        return ENOTSUP;
    }
Actions
Copy link
 #5

Updated by laforge over 6 years ago

On Mon, Jul 31, 2017 at 02:03:12PM +0000, pespin [REDMINE] wrote:

Jul 31 12:38:27 sysmobts2100 osmo-bts-lc152937: <0006> ../../../git/src/osmo-bts-litecell15/l1_if.c:938 PH-DATA-INDICATION for unknown sapi 0

would be interesting to decode the data_ind->sapi using lc15bts_l1sapi_names and add that to the log.

Actions
Copy link
 #6

Updated by pespin over 6 years ago

Apparently sapi 0 is:

typedef enum GsmL1_Sapi_t
{    
    GsmL1_Sapi_Idle = 0,    ///< Idle burst pseudo-channel

Which I think also makes sense when DTX is in use. Case for this SAPI is missing in chan_nr_by_sapi() and 0 is returned. I don't know how does this channel nr calculation work, in this case it looks like we may want to use ts->pchan to know what to return. Any hint on where to find specific documentation about this is welcome.

This looks like a change in the firmware from sysmo, because I don't recall seeing this issue there when doing tests.

Actions
Copy link
 #7

Updated by pespin over 6 years ago

Patch to add the name provided here: https://gerrit.osmocom.org/#/c/3397

Actions
Copy link
 #8

Updated by laforge over 6 years ago

On Mon, Jul 31, 2017 at 05:41:09PM +0000, pespin [REDMINE] wrote:

Apparently sapi 0 is:

>     GsmL1_Sapi_Idle = 0,    ///< Idle burst pseudo-channel              
>

yes, I know. However, what the 'master' code logs so far is the
osmocom sapi after the conversion, not the pht layer sapi. Did you
actually implement logging of the phy layer sapi, as suggested?

Also, IMHO, it makes no sense to receive this SAPI on an uplink TCH.

Actions
Copy link
 #9

Updated by pespin over 6 years ago

  • Status changed from New to In Progress
  • Assignee set to pespin

Patch adding the channel name was merged.

Next steps: Check Nutaq's fork and if nothing related is found, contact them.

Actions
Copy link
 #10

Updated by pespin over 6 years ago

  • Status changed from In Progress to Stalled

An e-mail was sent to Nutaq ("Idle sapi received on uplink TCH") and waiting for an answer.

Actions
Copy link
 #11

Updated by laforge over 6 years ago

plase let's not keep tickets stalled for three months. Even if we're waiting for a respnonse from someone else, it is you (Pau, the ticket owner) responsibility to regularly re-ping that third party to try to get a response. This is the kind of activity I would expect to happen when doing the weekly ticket review on monday. Thanks for your attention!

Actions
Copy link
 #12

Updated by laforge over 6 years ago

  • Category set to osmo-bts-litecell15
Actions
Copy link
 #13

Updated by pespin over 6 years ago

I sent an e-mail today to follow up on the topic.

Actions
Copy link
 #14

Updated by mqng2 over 6 years ago

I am currenlty out of office today. I will be back on Dec 05 2017.

Actions
Copy link
 #15

Updated by pespin over 6 years ago

  • Status changed from Stalled to In Progress

According the Nuran there is a fix available for this issue in litecell15-fw.git d7f05ac0b0fc9aab497d952e30c0c87b223e6b16, which is comes with latest stable branch nrw/litecell15 (currently HEAD is 7d6e34f027c56343370bf959d821c22193b2b542).

We are nowadays using the older stable branch release at 68607b5c33a02883c50f9408f423234747407d99. I'll update recipes-sysmobts/firmware/lc15-firmware_git.bb in meta-sysmocom-bsp to the new HEAD 7d6e34f027c56343370bf959d821c22193b2b542 and test again.

Actions
Copy link
 #16

Updated by pespin about 6 years ago

  • Status changed from In Progress to Stalled

Stalled since we sent back our lc15 for reparation works. We can test this one once get it back.

Actions
Copy link
 #17

Updated by pespin about 6 years ago

  • Status changed from Stalled to Resolved
  • % Done changed from 0 to 100

I tested it today using latest stable firmware and everything's working fine, I don't see those errors anymore, with or without dtx enabled as described above.

I pushed the newest stable firmware to be used in sysmocom's 201705 meta-sysmocom-bsp bc8fdb3950294409225bb648a0b8f3cc5b908a3d.

Actions
Copy link

Also available in: Atom PDF

Add picture from clipboard (Maximum size: 48.8 MB)