MP3 radio station stopping

Support
1

Core Machine (Operating system/System info/Roon build number)

Linux x64/QNAP NAS/1.7 (667)

Network Details (Including networking gear model/manufacturer and if on WiFi/Ethernet)

Ethernet/Sotm 200Ultra

  • Buffer duration: increased from 0.2 to 0.4
  • Resync delay: 0.05

Audio Devices (Specify what device you’re using and its connection type - USB/HDMI/etc.)
Chord M Scaler (USB)/Chord Dave (Dual BNC)

Description Of Issue
MP3 based radio station 'RTVS Rádio_FM‘ is stopping and not auto-resuming when buffer runs out.
Station can run for about 5-20 minutes, then it gets cut off. I then need to press the play manually to get it going again.
Could you please check why Roon does not resume?
Increasing the buffer in SoTM settings helps a bit, but nevertheless it constantly stops anyway once the buffer runs out.

Thanks,
Stefan

4

I’ve managed to reproduce this, twice.

The second time I had VLC playing in parallel, without problems.

Relevant bit of logs (I think) here

Summary

11/03 14:04:00 Trace: [Lumin] [LowQuality 85.0x, 24/44 MP3 => 24/44] [PLAYING @ 51:14] -
11/03 14:04:05 Trace: [Lumin] [LowQuality 85.1x, 24/44 MP3 => 24/44] [PLAYING @ 51:19] -
11/03 14:04:10 Trace: [Lumin] [LowQuality 85.2x, 24/44 MP3 => 24/44] [PLAYING @ 51:24] -
11/03 14:04:12 Trace: [Lumin] [zoneplayer/raat] sync LUMIN D1: realtime=3089318936437 rtt=500us offset=-155881063us delta=-20649us drift=171144us in 3089.0825s (55.403ppm, 199.451ms/hr)
11/03 14:04:13 Info: [stats] 22580mb Virtual, 1570mb Physical, 500mb Managed, 284 Handles, 76 Threads
11/03 14:04:15 Trace: [Lumin] [LowQuality 85.1x, 24/44 MP3 => 24/44] [PLAYING @ 51:29] -
11/03 14:04:20 Trace: [Lumin] [LowQuality 85.1x, 24/44 MP3 => 24/44] [PLAYING @ 51:34] -
11/03 14:04:20 Trace: [broker/accounts] [heartbeat] now=11/03/2020 14:04:20 nextauthrefresh=11/03/2020 14:24:20 nextmachineallocate=11/03/2020 17:24:20
11/03 14:04:25 Trace: [Lumin] [LowQuality 85.2x, 24/44 MP3 => 24/44] [PLAYING @ 51:39] -
11/03 14:04:27 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:8041,“status”:“Dropout”}
11/03 14:04:27 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:3072,“status”:“Dropout”}
11/03 14:04:28 Info: [stats] 22580mb Virtual, 1570mb Physical, 504mb Managed, 284 Handles, 72 Threads
11/03 14:04:30 Trace: [Lumin] [LowQuality 85.2x, 24/44 MP3 => 24/44] [PLAYING @ 51:44] -
11/03 14:04:30 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:2665,“status”:“Dropout”}
11/03 14:04:31 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:1024,“status”:“Dropout”}
11/03 14:04:35 Trace: [Lumin] [LowQuality 85.2x, 24/44 MP3 => 24/44] [PLAYING @ 51:49] -
11/03 14:04:38 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:7273,“status”:“Dropout”}
11/03 14:04:40 Trace: [Lumin] [LowQuality 85.3x, 24/44 MP3 => 24/44] [PLAYING @ 51:54] -
11/03 14:04:41 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:5993,“status”:“Dropout”}
11/03 14:04:43 Info: [stats] 22580mb Virtual, 1570mb Physical, 505mb Managed, 284 Handles, 72 Threads
11/03 14:04:45 Trace: [Lumin] [LowQuality 85.3x, 24/44 MP3 => 24/44] [PLAYING @ 51:59] -
11/03 14:04:45 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:2665,“status”:“Dropout”}
11/03 14:04:45 Trace: [Lumin] [zoneplayer/raat] sync LUMIN D1: realtime=3122950706797 rtt=2000us offset=-155864293us delta=23822us drift=187914us in 3122.701s (60.177ppm, 216.637ms/hr)
11/03 14:04:45 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:12288,“status”:“Dropout”}
11/03 14:04:49 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:14697,“status”:“Dropout”}
11/03 14:04:50 Trace: [Lumin] [LowQuality 85.3x, 24/44 MP3 => 24/44] [PLAYING @ 52:04] -
11/03 14:04:52 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:13417,“status”:“Dropout”}
11/03 14:04:53 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:5120,“status”:“Dropout”}
11/03 14:04:53 Trace: Got ICY Stream Title [CHVRCHES-Death Stranding]
11/03 14:04:55 Trace: [Lumin] [LowQuality 85.3x, 24/44 MP3 => 24/44] [PLAYING @ 52:09] CHVRCHES-Death Stranding
11/03 14:04:56 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:8041,“status”:“Dropout”}
11/03 14:04:56 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:2048,“status”:“Dropout”}
11/03 14:04:58 Info: [stats] 22588mb Virtual, 1570mb Physical, 510mb Managed, 284 Handles, 77 Threads
11/03 14:04:59 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:2665,“status”:“Dropout”}
11/03 14:05:00 Trace: [Lumin] [LowQuality 85.4x, 24/44 MP3 => 24/44] [PLAYING @ 52:14] CHVRCHES-Death Stranding
11/03 14:05:00 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:6144,“status”:“Dropout”}
11/03 14:05:03 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:6265,“status”:“Dropout”}
11/03 14:05:03 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:11264,“status”:“Dropout”}
11/03 14:05:05 Trace: [Lumin] [LowQuality 85.4x, 24/44 MP3 => 24/44] [PLAYING @ 52:19] CHVRCHES-Death Stranding
11/03 14:05:06 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:889,“status”:“Dropout”}
11/03 14:05:07 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:17408,“status”:“Dropout”}
11/03 14:05:10 Trace: [Lumin] [LowQuality 85.3x, 24/44 MP3 => 24/44] [PLAYING @ 52:24] CHVRCHES-Death Stranding
11/03 14:05:10 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:14969,“status”:“Dropout”}
11/03 14:05:13 Info: [stats] 22588mb Virtual, 1570mb Physical, 512mb Managed, 284 Handles, 77 Threads
11/03 14:05:14 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:12665,“status”:“Dropout”}
11/03 14:05:14 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“samples”:8192,“status”:“Dropout”}
11/03 14:05:14 Warn: [Lumin] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
11/03 14:05:14 Trace: [Lumin] [zoneplayer/raat] too many dropouts. stopping stream
11/03 14:05:14 Warn: [zone Lumin] Track Stopped Due to Slow Media
11/03 14:05:14 Trace: Attempting to play channel URL: icy://live.slovakradio.sk:8000/FM_256.mp3
11/03 14:05:14 Info: [Lumin] [zoneplayer] advance didn’t change the track. returning short read
11/03 14:05:14 Info: [zone Lumin] OnPlayFeedback StoppedEndOfMediaUnnatural
11/03 14:05:14 Info: [zone Lumin] OnPlayFeedback Stopped
11/03 14:05:14 Info: [zone Lumin] Canceling Pending Sleep
11/03 14:05:14 Info: [zone Lumin] Canceling Pending Sleep
11/03 14:05:14 Info: [transport/autosleep] scheduling autosleep for LUMIN D1 (23:1:4c494e4e-3d9b-11e1-b86c-0016e85d6512) [IsAvailable=True] in 900 seconds
11/03 14:05:14 Trace: [Lumin] [LowQuality, 24/44 MP3 => 24/44] [STOPPED @ 0:00] CHVRCHES-Death Stranding
11/03 14:05:14 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
11/03 14:05:14 Trace: [Lumin] [zoneplayer/raat] Endpoint LUMIN D1 State Changed: Playing => Prepared
11/03 14:05:14 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] SENT [4462]{“request”:“end_stream”}
11/03 14:05:14 Debug: [raat/tcpaudiosource] disconnecting
11/03 14:05:14 Info: [audio/env] [zoneplayer] All streams were disposed
11/03 14:05:15 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
11/03 14:05:15 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [14] {“status”:“Ended”}
11/03 14:05:15 Trace: [LUMIN D1 @ 192.168.1.113:46408] [raatclient] GOT [4462] {“status”:“Success”}
11/03 14:05:15 Info: sleep 268ms after flush
11/03 14:05:15 Info: [Lumin] [zoneplayer] Playing: channel://icy%3a%2f%2flive.slovakradio.sk%3a8000%2fFM_256.mp3
11/03 14:05:15 Debug: [easyhttp] [247] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 565 ms, status code: 200
11/03 14:05:15 Trace: [radio/library] got location GB
11/03 14:05:15 Debug: [easyhttp] [246] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 574 ms, status code: 200
11/03 14:05:15 Trace: [radio/library] got location GB
11/03 14:05:15 Trace: Got ICY Stream Title [CHVRCHES-Death Stranding]
11/03 14:05:16 Debug: [easyhttp] [248] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 457 ms, status code: 200
11/03 14:05:16 Debug: [easyhttp] [249] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 548 ms, status code: 200
11/03 14:05:16 Debug: [easyhttp] [250] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 197 ms, status code: 400
11/03 14:05:16 Debug: [easyhttp] [251] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 245 ms, status code: 200
11/03 14:05:17 Info: [Lumin] [zoneplayer] Open Result (Playing):Result[Status=Success]
11/03 14:05:17 Info: [Lumin] [zoneplayer] Aborting play because track changed
11/03 14:05:17 Warn: [streammediafile] error reading stream: Stream does not support reading.
11/03 14:05:17 Info: [audio/env] [zoneplayer] All streams were disposed
11/03 14:05:28 Info: [stats] 22612mb Virtual, 1549mb Physical, 526mb Managed, 280 Handles, 75 Threads
11/03 14:05:43 Info: [stats] 22540mb Virtual, 1549mb Physical, 526mb Managed, 280 Handles, 66 Threads
11/03 14:05:58 Info: [stats] 22556mb Virtual, 1549mb Physical, 529mb Managed, 280 Handles, 72 Threads
11/03 14:06:13 Info: [stats] 22556mb Virtual, 1549mb Physical, 529mb Managed, 280 Handles, 71 Threads

2 Likes
5

Hello @StefanK, does this happen with other radio stations or just this one? Also, does this happen with local or TIDAL/Qobuz content?

6

Hi @nuwriy,

I’ve noticed this only on this station, which is the main one we listen to. No issue with Tidal content.
Regardless that Roon gets some drop outs, it should restart the stream if it can in my opinion.
When a user selects to listen to a radio, the radio should keep playing.
As mentioned by Brian it works ok in VLC (which is able to recover from the dropouts I suppose) .

7

I see this regularly on a South Africa site 702 , a pain , we even bought a tranny to listen the news

8

Hello @StefanK, I tested this radio stream to my DAC and system output for about 25 minutes each and wasn’t able to reproduce this. Next time the issue occurs, could you please reproduce this one more time and reply here with a timestamp when you do so I can enable diagnostics for your account?

9

Hi @nuwriy, I just ran the test with my Sotm SmS-Ultra + Chord Dave setup:
Start: 06/11/2020 at 8:27 CET
Abrupt stop: 06/11/2020 at 8:38 CET

Start: 06/11/2020 at 9:13 CET
Abrupt stop: 06/11/2020 at 9:15 CET

For this second test I played the same station also to my Windows PC running Roon.
Start: 06/11/2020 at 9:13 CET
Abrupt stop: N/A

Then I realized Sotm just released a new firmware version 5.0. So I ran another test after firmware update, but the results are the same:
Start: 06/11/2020 at 11:18 CET
Abrupt stop: 06/11/2020 at 11:42 CET

I think the stopping of streams only happens with Sotm sms-200ultra.
It is running ‘Roon Ready’ 1.1.38.

10

Hello @StefanK, my apologies for the delayed reply. I was able to successfully collect diagnostics from your core and have since sent a report over to our QA team for further analysis. I’ll return once I have their feedback, thanks for the timestamps!

12

Hello @StefanK, our QA team listened to this station for about an hour and didn’t have instances of the station stopping. Is the issue still occurring for you?

13

Hi @nuwriy, unfortunately yes.
As I mentioned before, it only happens when I use the Sotm SmS-Ultra with the Roon Ready running on it.
Has the QA team tested with this type of setup?

14

This topic was automatically closed 365 days after the last reply. New replies are no longer allowed.