Bug #2412
closedosmo-bts-litecell15 RTP clock drift
100%
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
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
Updated by pespin over 6 years ago
- Related to Bug #2335: Steady increasing clock drift in RTP from source BTS added
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; }
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.
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.
Updated by pespin over 6 years ago
Patch to add the name provided here: https://gerrit.osmocom.org/#/c/3397
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.
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.
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.
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!
Updated by mqng2 over 6 years ago
I am currenlty out of office today. I will be back on Dec 05 2017.
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.
Updated by pespin over 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.
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.