Roon loops endlessly with HLS stream from ICI Radio-Canada Première Montreal (ref#F75CM5)

Hi! What’s not quite right with Roon?

· None of the above quite fits

None of the above quite fits

· None of these quite match

Tell us what's going on

· Station `ICI Radio-Canada Première Montreal` and associates use HLS streams like

https://rcavliveaudio.akamaized.net/hls/live/2006635/P-2QMTL0_MTL/master.m3u8



These consist of .ts chunks. Trying to play these causes Roon to endlessly loop. Stopping the playback and starting a different stream does not stop the endless logging although the new stream plays.

In the following log extract, the rcaliveaudio stream is the stream that never stops, and the drliveradio2 is the good playing stream (HLS with .ts chunks again) which I started and then stopped - but the logging continues. I needed to restart roonserver to stop the endless logging.




08/08 09:15:25 [Local 08/08 10:15:25] Debug: [easyhttp] [19760] GET to https://rcavliveaudio.akamaized.net/hls/live/2006635/P-2QMTL0_MTL/adaptive_48/x75g504d/00000013/media_ao_24965.ts returned after 160 ms, status code: 200, request body size: 0 B

08/08 09:15:25 [Local 08/08 10:15:25] Trace: [SM-T830] [LowQuality 31.4x, 24/44 AAC => 24/48] [PLAYING @ 3:11] DR P8 Jazz

08/08 09:15:25 [Local 08/08 10:15:25] Debug: [easyhttp] [19761] GET to https://rcavliveaudio.akamaized.net/hls/live/2006635/P-2QMTL0_MTL/adaptive_48/chunklist_ao.m3u8 returned after 51 ms, status code: 200, request body size: 0 B

08/08 09:15:25 [Local 08/08 10:15:25] Debug: [easyhttp] [19762] GET to https://rcavliveaudio.akamaized.net/hls/live/2006635/P-2QMTL0_MTL/adaptive_48/chunklist_ao.m3u8 returned after 51 ms, status code: 200, request body size: 0 B

08/08 09:15:26 [Local 08/08 10:15:26] Debug: [easyhttp] [19763] GET to https://drliveradio2.akamaized.net/hls/live/2118698/p8/playlist-320000.m3u8 returned after 113 ms, status code: 200, request body size: 0 B

08/08 09:15:26 [Local 08/08 10:15:26] Debug: [easyhttp] [19764] GET to https://drliveradio2.akamaized.net/hls/live/2118698/p8/02333/seg320000-04665694.ts returned after 106 ms, status code: 200, request body size: 0 B

08/08 09:15:26 [Local 08/08 10:15:26] Warn: [SM-T830] [zoneplayer/raat] long rtt sync Default Output: realtime=193529237468 rtt=30822us offset=-7894071us delta=29973us drift=29736us in 193.0177407s (154.061ppm, 554.618ms/hr)

08/08 09:15:27 [Local 08/08 10:15:27] Debug: [easyhttp] [19766] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/chunklist_ao.m3u8 returned after 116 ms, status code: 200, request body size: 0 B

08/08 09:15:27 [Local 08/08 10:15:27] Debug: [easyhttp] [19765] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/chunklist_ao.m3u8 returned after 120 ms, status code: 200, request body size: 0 B

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [zone SM-T830] Stop

08/08 09:15:27 [Local 08/08 10:15:27] Info: [zone SM-T830] OnPlayFeedback Stopped

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [zone SM-T830] Stop

08/08 09:15:27 [Local 08/08 10:15:27] Info: [zone SM-T830] Canceling Pending Sleep

08/08 09:15:27 [Local 08/08 10:15:27] Info: [zone SM-T830] Canceling Pending Sleep

08/08 09:15:27 [Local 08/08 10:15:27] Debug: [easyhttp] [19461] GET to https://api.roonlabs.net/internetradio/2/api/stations/7a309278-0bfc-43c5-9774-34ee261a5153/metadata?format=msgpack&wait=True&timestamp=-1& canceled after 89951 ms

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [SM-T830] [LowQuality 31.4x, 24/44 AAC => 24/48] [STOPPED @ 0:00] DR P8 Jazz

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [SM-T830] [zoneplayer/raat] Endpoint Default Output State Changed: Playing => Prepared

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [Default Output] [raatclient] SENT [212]{"request":"end_stream"}

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [Default Output] [raatclient] SENT [213]{"request":"teardown"}

08/08 09:15:27 [Local 08/08 10:15:27] Info: [audio/env] [zoneplayer -> stream] All streams were disposed

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [SM-T830] [zoneplayer/raat] Endpoint Default Output State Changed: Prepared => Idle

08/08 09:15:27 [Local 08/08 10:15:27] Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed

08/08 09:15:27 [Local 08/08 10:15:27] Info: [audio/env] [zoneplayer] All streams were disposed

08/08 09:15:27 [Local 08/08 10:15:27] Debug: [raat/tcpaudiosource] disconnecting

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [Default Output] [raatclient] GOT [15] {"status":"Ended"}

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [Default Output] [raatclient] GOT [212] {"status":"Success"}

08/08 09:15:27 [Local 08/08 10:15:27] Trace: [Default Output] [raatclient] GOT [14] {"status":"Teardown"}

08/08 09:15:27 [Local 08/08 10:15:27] Info: sleep 241ms after flush

08/08 09:15:27 [Local 08/08 10:15:27] Debug: [easyhttp] [19768] GET to https://rcavliveaudio.akamaized.net/hls/live/2006979/M-7QMTL0_MTL/adaptive_128/chunklist_ao.m3u8 returned after 134 ms, status code: 200, request body size: 0 B

08/08 09:15:28 [Local 08/08 10:15:28] Debug: [easyhttp] [19769] GET to https://rcavliveaudio.akamaized.net/hls/live/2006979/M-7QMTL0_MTL/adaptive_128/mn8q8b7e/00000013/media_ao_26503.ts returned after 286 ms, status code: 200, request body size: 0 B

08/08 09:15:28 [Local 08/08 10:15:28] Debug: [easyhttp] [19770] GET to https://rcavliveaudio.akamaized.net/hls/live/2006635/P-2QMTL0_MTL/adaptive_48/chunklist_ao.m3u8 returned after 163 ms, status code: 200, request body size: 0 B

08/08 09:15:28 [Local 08/08 10:15:28] Debug: [easyhttp] [19771] GET to https://rcavliveaudio.akamaized.net/hls/live/2006635/P-2QMTL0_MTL/adaptive_48/chunklist_ao.m3u8 returned after 161 ms, status code: 200, request body size: 0 B

08/08 09:15:28 [Local 08/08 10:15:28] Debug: [easyhttp] [19772] GET to https://rcavliveaudio.akamaized.net/hls/live/2006979/M-7QMTL0_MTL/adaptive_128/chunklist_ao.m3u8 returned after 51 ms, status code: 200, request body size: 0 B

08/08 09:15:29 [Local 08/08 10:15:29] Info: [stats] 13100mb Virtual; 1862mb Physical = 697mb GC-committed (633mb Managed-live = 90% of committed) + 1165mb Native; 398 Handles, 84 Threads, 0.33% of runtime in GC pauses, 49ms GC pause in last window (0.33% of window)

08/08 09:15:29 [Local 08/08 10:15:29] Debug: [easyhttp] [19767] POST to https://api.roonlabs.net/internetradio/2/api/reporting/internetradioplayback/events?format=msgpack& returned after 2259 ms, status code: 200, request body size: 171 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19773] POST to https://api.roonlabs.net/internetradio/2/api/reporting/internetradioplayback/plays?format=msgpack& returned after 194 ms, status code: 200, request body size: 176 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19774] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/chunklist_ao.m3u8 returned after 213 ms, status code: 200, request body size: 0 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19775] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/chunklist_ao.m3u8 returned after 213 ms, status code: 200, request body size: 0 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19776] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/h5v5dr14/00000020/media_ao_39116.ts returned after 342 ms, status code: 200, request body size: 0 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19777] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/h5v5dr14/00000020/media_ao_39116.ts returned after 343 ms, status code: 200, request body size: 0 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19778] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/chunklist_ao.m3u8 returned after 51 ms, status code: 200, request body size: 0 B

08/08 09:15:30 [Local 08/08 10:15:30] Debug: [easyhttp] [19779] GET to https://rcavliveaudio.akamaized.net/hls/live/2007000-b/MUSE/adaptive_192/chunklist_ao.m3u8 returned after 51 ms, status code: 200, request body size: 0 B

Tell us about your home network

· Rock OS latest version

Fixed internally, but will take some time to ship this code to prod.

Hello @BrianW

Thanks for the report.

As Vova noted, the fix is already in internally. It has not shipped yet, so please watch the release notes, and it will be listed there when it goes out.

Until then, restarting Roon Server is the way to clear the runaway logging, which you had already worked out for yourself.