search

philippe44 / LMS-Raop

LMS to AirPlay bridge
67 stars 12 forks source link

Help debugging raopbridge disconnections in LMS #19

Closed fmalfatto closed 3 years ago

fmalfatto commented 4 years ago

Hi, I have my LMS on Raspberry, configured to wake up at 7:00 in the morning, and sending a webradio output to an Airplay Sony speaker. It works great for a lot of webradios, but one disconnects after a while. I tried to change timeouts and receive buffers, but nothing worked. Some days it works for more than 15 minutes, and somewhen crashes and then restarts before of the timeout. How can I understand why? I have a very fast internet connection (130 Mbps and a fast local LAN), The speaker and LMS are cabled, no wifi problems between LMS and speakers. Audio buffer is up to 10 sec, connection buffer is 50 sec, in both network and raopbridge

Thank you in advance

This is the log, cleaned from thousands of "Tick" lines (btw, how can I avoid such lines?): [rao.log](https://github.com/philippe44/LMS-to-Raop/files/5473959/rao.log)

philippe44 commented 4 years ago

There is no log

fmalfatto commented 4 years ago

rao.log Uploaded again ..

philippe44 commented 4 years ago

So it's a few specific webradio that does not work? Do you have an URL?

fmalfatto commented 4 years ago

https://icestreaming.rai.it/2.mp3 or http://icestreaming.rai.it/2.mp3 It works well on browsers and if called via LMS to Chromecast devices. It looks as in the morning the webradio does not support many connections activated from wake-ups so I tried to modify buffers and timeouts without results. During the day it does not disconnect, or very rarely.

This is today's log:

`[07:20:06.305479] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:25992 nb:1 (mis:0) [07:20:06.306012] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:25993 nb:1 (mis:0) [07:20:06.306135] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:25994 nb:1 (mis:0) [07:20:06.306249] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:25995 nb:1 (mis:0) [07:20:06.306809] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:25996 nb:1 (mis:0) [07:20:06.392870] PlayerThread:409 [0x10dc48]: tick 3737214840 [07:20:06.392902] PlayerThread:409 [0x110510]: tick 3737214840 [07:20:06.392980] PlayerThread:409 [0x10f778]: tick 3737214840 [07:20:06.458114] PlayerThread:409 [0x10e9e0]: tick 3737214906 [07:20:07.307925] _raopcl_send_sync:1252 [0x75f1c308]: sync ntp:3813373205.2636382208 (ts:168169758367570) [07:20:07.393078] PlayerThread:409 [0x10dc48]: tick 3737215841 [07:20:07.393168] PlayerThread:409 [0x110510]: tick 3737215841 [07:20:07.393168] PlayerThread:409 [0x10f778]: tick 3737215841 [07:20:07.458244] PlayerThread:409 [0x10e9e0]: tick 3737215906 [07:20:08.149512] _rtp_timing_thread:1321 [0x75f1c308]: NTP sync: 3813373208.641985941 (ref 3813373208.636663743) [07:20:08.158871] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:26100 nb:1 (mis:0) [07:20:08.158962] _rtp_control_thread:1424 [0x75f1c308]: retransmit packet sn:26101 nb:1 (mis:0) [07:20:08.393289] PlayerThread:409 [0x10dc48]: tick 3737216841 [07:20:08.393371] PlayerThread:409 [0x10f778]: tick 3737216841 [07:20:08.393371] PlayerThread:409 [0x110510]: tick 3737216841 [07:20:08.458392] PlayerThread:409 [0x10e9e0]: tick 3737216906 [07:20:08.458485] PlayerThread:413 [0x10e9e0]: Disconnecting 3737216906 [07:20:09.393445] PlayerThread:409 [0x10dc48]: tick 3737217841 [07:20:09.393538] PlayerThread:409 [0x110510]: tick 3737217841 [07:20:09.393538] PlayerThread:409 [0x10f778]: tick 3737217841 [07:20:10.152135] exec_request:622 [0x75f1b6f8]: ----> : write FLUSH rtsp://192.168.1.142/1238872375 RTSP/1.0^M RTP-Info: seq=26126;rtptime=313919091^M CSeq: 489^M User-Agent: iTunes/7.6.2 (Windows; N;)^M Client-Instance: 7037b0c7bfe397a9^M DACP-ID: 1A2B3D4EA1B2C3D4^M Active-Remote: 2750729771^M Session: 1^M ^M

[07:20:10.153422] exec_request:648 [0x75f1b6f8]: <------ : 200: request ok [07:20:10.153684] exec_request:658 [0x75f1b6f8]: <------ : RTP-Info: rtptime=313918738 [07:20:10.153907] exec_request:658 [0x75f1b6f8]: <------ : Server: AirTunes/141.9 [07:20:10.154024] exec_request:658 [0x75f1b6f8]: <------ : CSeq: 489 [07:20:10.154142] exec_request:622 [0x75f1b6f8]: ----> : write TEARDOWN rtsp://192.168.1.142/1238872375 RTSP/1.0^M CSeq: 490^M User-Agent: iTunes/7.6.2 (Windows; N;)^M Client-Instance: 7037b0c7bfe397a9^M DACP-ID: 1A2B3D4EA1B2C3D4^M Active-Remote: 2750729771^M Session: 1^M ^M

[07:20:10.167287] exec_request:648 [0x75f1b6f8]: <------ : 200: request ok [07:20:10.167513] exec_request:658 [0x75f1b6f8]: <------ : Server: AirTunes/141.9 [07:20:10.167632] exec_request:658 [0x75f1b6f8]: <------ : CSeq: 490 [07:20:10.393630] PlayerThread:409 [0x10dc48]: tick 3737218841 [07:20:10.393675] PlayerThread:409 [0x110510]: tick 3737218841 [07:20:10.393744] PlayerThread:409 [0x10f778]: tick 3737218841 [07:20:11.167884] PlayerThread:409 [0x10e9e0]: tick 3737219615 `

philippe44 commented 3 years ago

Did you sort this out?

fmalfatto commented 3 years ago

No, I changed the local player from an airplay Sony to a google home and also in chromecast only that one radio station is interrupted too. So I'm sure that is a problem involving the radio station server, not my players. May close the issue. Thank you!

philippe44 commented 3 years ago

Thanks for answering :-)