Project

General

Profile

Bug #2412

osmo-bts-litecell15 RTP clock drift

Added by pespin 18 days ago. Updated 16 days ago.

Status:
In Progress
Priority:
Normal
Assignee:
Category:
-
Target version:
-
Start date:
07/31/2017
Due date:
% Done:

0%

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 BTS Closed 06/21/2017

History

#1 Updated by pespin 18 days 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

#2 Updated by pespin 18 days ago

  • Description updated (diff)

#3 Updated by pespin 18 days ago

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

#4 Updated by pespin 18 days 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;
    }

#5 Updated by laforge 18 days 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.

#6 Updated by pespin 18 days 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.

#7 Updated by pespin 18 days ago

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

#8 Updated by laforge 18 days 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.

#9 Updated by pespin 16 days 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.

Also available in: Atom PDF