<html>
<head>
<meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
</head>
<body text="#000000" bgcolor="#FFFFFF">
<p>Gentlemen,</p>
<p>While upgrading to <a
href="http://download.opensuse.org/repositories/network:/osmocom:/nightly/Debian_9.0/armhf/osmo-bts-trx_0.8.1.199.5c93_armhf.deb">osmo-bts-trx_0.8.1.199.5c93_armhf.deb</a>
solved most of the problem, the main</p>
<p>issue seems to remain. The latest version reduced failure rate
from 1-3 / hour, to 18 hours, but</p>
<p>did not eliminate the problem, i.e. a spinning trx-uhd. I enclose
a snippet from console:</p>
<p>Thu Dec 27 18:53:00 2018 DMAIN <0000> Transceiver.cpp:1005
[tid=3007431760] new latency: 7:33599 (underrun 1:1683315 vs
6:1683313)<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DMAIN <0000> Transceiver.cpp:1005
[tid=3007431760] new latency: 7:33600 (underrun 6:1683326 vs
1:1683325)<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DMAIN <0000> Transceiver.cpp:1005
[tid=3007431760] new latency: 7:33601 (underrun 5:1683338 vs
6:1683336)<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DDEV <0002> UHDDevice.cpp:1319
[tid=2997609552] Packet loss between host and device at 103454
sec.<br>
Thu Dec 27 18:53:00 2018 DMAIN <0000> Transceiver.cpp:1005
[tid=3007431760] new latency: 7:33602 (underrun 6:1683350 vs
5:1683348)<br>
<br>
</p>
<p>as you can see this is very similar to running
osmo-bts-trx_0.8.1.194.8564_armhf.deb, but only happened after 18
hours seemingly stable.</p>
<p>syslog says:</p>
<p>Dec 27 18:53:37 localhost osmo-bts-trx[2166]:
#033[0;m#033[1;33m<000b> trx_if.c:178 No satisfactory
response from transceiver for phy0.0 (CMD POWEROFF)<br>
Dec 27 18:53:39 localhost osmo-bts-trx[2166]:
#033[0;m#033[1;33m<000b> trx_if.c:178 No satisfactory
response from transceiver for phy0.0 (CMD POWEROFF)<br>
Dec 27 18:53:41 localhost osmo-bts-trx[2166]:
#033[0;m#033[1;33m<000b> trx_if.c:178 No satisfactory
response from transceiver for phy0.0 (CMD POWEROFF)<br>
Dec 27 18:53:43 localhost osmo-bts-trx[2166]:
#033[0;m#033[1;33m<000b> trx_if.c:178 No satisfactory
response from transceiver for phy0.0 (CMD POWEROFF)<br>
</p>
<p>so bts-trx and trx-uhd have lost synch with each other.</p>
<p>Just restarting trx-uhd resolved the situation, if bts-trx had
timed out, it would have restarted, and I assume the whole thing
recovered.</p>
<p>next time it stops, I will kill -9 bts-trx, it should restart
auto, and I will see if it does not matter WHICH process is
restarted, I earlier</p>
<p>believed the problem was in the trx-uhd, but replacing bts-trx
was the factor to go to "almost stable".</p>
<p>I will report my findings, if you have any more intelligent
suggestions to how to narrow this down, please go ahead.....</p>
<p>Regards,</p>
<p>Gullik<br>
</p>
<p><br>
</p>
<p><br>
</p>
<div class="moz-cite-prefix">On 2018-12-27 10:15, Gullik Webjorn
wrote:<br>
</div>
<blockquote type="cite"
cite="mid:222bd978-aaa1-2035-8bb2-b3a2dbf9d835@corevalue.se">I
have had 100% good stability over the night. However, osmo-bts-trx
logs the following every 5-10 minutes.
<br>
<br>
There has been no "activity" nightly on the gsm network.
<br>
<br>
Gullik
<br>
<br>
Dec 27 09:08:57 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:09:00 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
Dec 27 09:10:12 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:10:15 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
Dec 27 09:12:03 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:12:03 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:920 Store content res.
(dl=0xb67ec498)
<br>
Dec 27 09:12:04 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:12:04 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:12:05 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:12:06 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
<br>
Dec 27 09:17:27 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:17:27 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:920 Store content res.
(dl=0xb67ec498)
<br>
Dec 27 09:17:28 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:17:29 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:17:29 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:17:30 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
<br>
Dec 27 09:32:46 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:32:46 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:920 Store content res.
(dl=0xb67ec498)
<br>
Dec 27 09:32:48 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:32:48 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:32:48 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:32:49 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
<br>
Dec 27 09:42:04 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:42:05 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:920 Store content res.
(dl=0xb67ec498)
<br>
Dec 27 09:42:06 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:42:06 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:42:06 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:42:08 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
<br>
Dec 27 09:47:32 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 09:47:32 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:920 Store content res.
(dl=0xb67ec498)
<br>
Dec 27 09:47:33 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:47:34 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:47:34 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 09:47:35 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
<br>
Dec 27 10:02:51 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:741
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN ACT ACK
<br>
Dec 27 10:02:51 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:920 Store content res.
(dl=0xb67ec498)
<br>
Dec 27 10:02:52 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 10:02:53 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 10:02:53 localhost osmo-bts-trx[20109]:
#033[0;m<0011> lapd_core.c:1556 N(S) sequence error: N(S)=0,
V(R)=1 (dl=0xb67ec498 state LAPD_STATE_MF_EST)
<br>
Dec 27 10:02:54 localhost osmo-bts-trx[20109]:
#033[0;m#033[1;35m<0000> rsl.c:720
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) (ss=0) SDCCH Tx CHAN REL ACK
<br>
<br>
</blockquote>
</body>
</html>