1.3 Fails to Play on Endpoints Except to HQPlayer (Too many dropouts error) - [RESOLVED]

Support
1

After the upgrade to Roon 1.3 I am unable to play to any of my Roon endpoints except for HQPlayer. I’ve tried a microRendu (RoonReady), directly to my Windows 10 laptop running Roon, and to my Android phone. Attempting to play a file stalls for a bit and then an error shows saying: “Roon: An audio file is loading slowly. This may indicate a performance or hardware problem”. All endpoints have the the latest 196 build.

The logs when attempting to play are shown below. It looks like it starts playing but then gets “Dropouts”, with finally an error indicating “Too many dropouts”

My Roon Server is running on a ReadyNAS NAS with a Core i7 and 16GB of ram. It runs a customized version of Debian Jessie. Roon 1.2 did not have this problem previously, did any of the dependencies change? Any help would be appreciated!

Laptop OS Mixer:

02/04 13:06:49 Trace: [zone] [Laptop OS Mixer] Playing 1 Items
02/04 13:06:49 Trace: [zone] [Laptop OS Mixer] Selecting Source
02/04 13:06:49 Trace: [zone] [Laptop OS Mixer] Playing TransportItem
02/04 13:06:49 Trace: [musicpowerstate] music is playing, preventing idle sleep
02/04 13:06:49 Trace: [tidal/http] GET https://api.tidalhifi.com/v1/tracks/26420471/streamurl?countryCode=US&sessionId=00711965-f213-4428-8377-f4f0c90c9e9f&soundQuality=LOSSLESS => Success
02/04 13:06:49 Trace: [tidal/http] GET https://api.tidalhifi.com/v1/tracks/26420471?countryCode=US&sessionId=00711965-f213-4428-8377-f4f0c90c9e9f& => Success
02/04 13:06:49 Info: [zoneplayer/base] Playing: http://c2.audio-pop.tidal.com/17402462/7d2cd7a5514942857301ab82f8eb5c76_26.flac
02/04 13:06:50 Info: [zoneplayer/base]     Open Result (Playing):Result[Status=Success]
02/04 13:06:50 Info: [zoneplayer/base] Starting playback
02/04 13:06:50 Trace: [zoneplayer/raat] StartStream 961861110: Sooloos.Broker.Transport.RaatZonePlayer+Endpoint => StreamParams[SampleRate=44100, BitsPerSample=16, Channels=2, Layout=CH_2_0 FrameCount=21168000] streamid=StreamParams[SampleRate=44100, BitsPerSample=16, Channels=2, Layout=CH_2_0 FrameCount=21168000]
02/04 13:06:50 Trace: [transport/raatclient] SENT [37]{"request":"setup","format":{"sample_type":"pcm","sample_rate":44100,"bits_per_sample":16,"channels":2}}
02/04 13:06:50 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality
------------------------------------------------------------

02/04 13:06:50 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality
------------------------------------------------------------

02/04 13:06:50 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/480 sec
02/04 13:06:51 Trace: [transport/raatclient] GOT [37] {"status":"OutputMessage","message":{"signal_path":[{"quality":"high","method":"wasapi_shared","type":"output"}]}}
02/04 13:06:51 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality
------------------------------------------------------------

02/04 13:06:51 Trace: [transport/raatclient] GOT [37] {"clock_port":58883,"status":"Success","audio_port":58884}
02/04 13:06:51 Trace: [zoneplayer/raat] Endpoint System Output State Changed: Idle => Prepared
02/04 13:06:51 Trace: [zoneplayer/raat] synced to endpoint clock. realtime=29894200 rtt=1903us offset=-289860517us delta=-289860517us
02/04 13:06:51 Trace: [transport/raatclient] SENT [38]{"request":"stream","stream_id":961861110,"first_seq":2211,"nak_port":42865,"buffer_size":2}
02/04 13:06:51 Trace: [transport/raatclient] GOT [38] {"status":"Buffering"}
02/04 13:06:51 Trace: [zoneplayer/raat] Endpoint System Output State Changed: Prepared => Buffering
02/04 13:06:51 Trace: [raat/audiosource] setting stream bitrate to 1411200 (2822400 with headroom)
02/04 13:06:51 Trace: [zoneplayer/raat] [zoneplayer/raat] selected System Output as clock master
02/04 13:06:51 Trace: [prebuffer] status 441000/441000 (100%) @ 0/480 sec
02/04 13:06:53 Info: [stats] 6019mb Virtual, 1482mb Physical, 618mb Managed, 0 Handles, 68 Threads
02/04 13:06:56 Trace: [streamingmediafile] finished caching http://c2.audio-pop.tidal.com/17402462/7d2cd7a5514942857301ab82f8eb5c76_26.flac
02/04 13:07:01 Warn: [zoneplayer/raat] Endpoint failed to become ready in 10s. Proceeding in state Buffering
02/04 13:07:01 Trace: [zoneplayer/raat] Endpoint System Output State Changed: Buffering => Ready
02/04 13:07:01 Trace: [zoneplayer/raat] wait for ready in 9999ms
02/04 13:07:01 Trace: [zoneplayer/raat] Adjusting playback start offset from 270ms to 150ms
02/04 13:07:01 Trace: [zoneplayer/raat] Starting at streamsample 0 and time 10338320200 min_offset=270ms offset=270ms
02/04 13:07:01 Trace: [transport/raatclient] SENT [39]{"request":"start","time":10588351200,"stream_sample":0}
02/04 13:07:01 Trace: [zoneplayer/raat] Endpoint System Output State Changed: Ready => Playing
02/04 13:07:01 Trace: [prebuffer] status 441000/441000 (100%) @ 2/480 sec
02/04 13:07:01 Trace: [transport/raatclient] GOT [38] {"status":"Playing"}
02/04 13:07:01 Trace: [transport/raatclient] GOT [39] {"status":"Success"}
02/04 13:07:01 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":8960}
02/04 13:07:02 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":21952}
02/04 13:07:02 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":21952}
02/04 13:07:03 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":22400}
02/04 13:07:03 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":21952}
02/04 13:07:04 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":21952}
02/04 13:07:04 Trace: [transport/raatclient] GOT [38] {"status":"Dropout","samples":22400}
02/04 13:07:04 Warn: [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
02/04 13:07:04 Trace: [zoneplayer/raat] too many dropouts. stopping stream
02/04 13:07:04 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
02/04 13:07:04 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
02/04 13:07:04 Trace: [zoneplayer/raat] Endpoint System Output State Changed: Playing => Prepared
02/04 13:07:04 Trace: [transport/raatclient] SENT [40]{"request":"end_stream"}
02/04 13:07:04 Warn: Track Stopped Due to Slow Media
02/04 13:07:05 Trace: [transport/raatclient] GOT [38] {"status":"Ended"}
02/04 13:07:05 Trace: [transport/raatclient] GOT [40] {"status":"Success"}
02/04 13:07:08 Info: [stats] 5998mb Virtual, 1465mb Physical, 605mb Managed, 0 Handles, 67 Threads
02/04 13:07:09 Trace: [zone] no playback for 5s, suspending to release audio device
02/04 13:07:09 Trace: [zone] [Laptop OS Mixer] Suspend
02/04 13:07:09 Trace: [zone] [Laptop OS Mixer] Stop

Nexus 6:

[code]02/03 19:10:35 Trace: [zone] [Nexus 6] Playing 1 Items
02/03 19:10:35 Trace: [zone] [Nexus 6] Selecting Source
02/03 19:10:35 Trace: [zone] [Nexus 6] Playing TransportItem
02/03 19:10:35 Trace: [musicpowerstate] music is playing, preventing idle sleep
02/03 19:10:35 Info: [zoneplayer/base] Playing: /mnt/RoonStorage_32234e3ce80f1d8a4d4813b2d5baadf2ef7712f9/Enya/And Winter Came…_/01 And Winter Came…flac
02/03 19:10:35 Info: [zoneplayer/base] Open Result (Playing):Result[Status=Success]
02/03 19:10:35 Info: [zoneplayer/base] Starting playback
02/03 19:10:35 Trace: [zoneplayer/raat] StartStream 1298983173: Sooloos.Broker.Transport.RaatZonePlayer+Endpoint => StreamParams[SampleRate=44100, BitsPerSample=16, Channels=2, Layout=CH_2_0 FrameCount=8636544] streamid=StreamParams[SampleRate=44100, BitsPerSample=16, Channels=2, Layout=CH_2_0 FrameCount=8636544]
02/03 19:10:35 Info:
–[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 BitRate=694 Quality=Lossless
Raat Device=Default Output
Output OutputType=Local_SharedMode_Android Quality=HighQuality

02/03 19:10:35 Info:
–[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 BitRate=694 Quality=Lossless
Raat Device=Default Output
Output OutputType=Local_SharedMode_Android Quality=HighQuality

02/03 19:10:35 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/195 sec
02/03 19:10:35 Trace: [zoneplayer/raat] synced to endpoint clock. realtime=438435357400 rtt=872us offset=-87241980us delta=-87241980us
02/03 19:10:35 Trace: [transport/raatclient] SENT [13]{“request”:“stream”,“stream_id”:1298983173,“first_seq”:470,“nak_port”:37069,“buffer_size”:2}
02/03 19:10:35 Trace: [transport/raatclient] GOT [13] {“status”:“Buffering”}
02/03 19:10:35 Trace: [zoneplayer/raat] Endpoint Default Output State Changed: Prepared => Buffering
02/03 19:10:35 Trace: [raat/audiosource] setting stream bitrate to 1411200 (2822400 with headroom)
02/03 19:10:35 Trace: [zoneplayer/raat] [zoneplayer/raat] selected Default Output as clock master
02/03 19:10:35 Trace: [prebuffer] status 441000/441000 (100%) @ 0/195 sec
02/03 19:10:41 Info: [stats] 5743mb Virtual, 1446mb Physical, 546mb Managed, 0 Handles, 61 Threads
02/03 19:10:45 Warn: [zoneplayer/raat] Endpoint failed to become ready in 10s. Proceeding in state Buffering
02/03 19:10:45 Trace: [zoneplayer/raat] Endpoint Default Output State Changed: Buffering => Ready
02/03 19:10:45 Trace: [zoneplayer/raat] wait for ready in 9999ms
02/03 19:10:45 Trace: [zoneplayer/raat] Adjusting playback start offset from 222ms to 150ms
02/03 19:10:45 Trace: [zoneplayer/raat] Starting at streamsample 0 and time 448699520400 min_offset=222ms offset=222ms
02/03 19:10:45 Trace: [transport/raatclient] SENT [14]{“request”:“start”,“time”:448902197200,“stream_sample”:0}
02/03 19:10:45 Trace: [zoneplayer/raat] Endpoint Default Output State Changed: Ready => Playing
02/03 19:10:45 Trace: [prebuffer] status 441000/441000 (100%) @ 2/195 sec
02/03 19:10:45 Trace: [transport/raatclient] GOT [13] {“status”:“Playing”}
02/03 19:10:45 Trace: [transport/raatclient] GOT [14] {“status”:“Success”}
02/03 19:10:45 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:7617}
02/03 19:10:46 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:22050}
02/03 19:10:46 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:22050}
02/03 19:10:47 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:22050}
02/03 19:10:47 Trace: [push] restarting connection (Unable to read data from the transport connection: interrupted.)
02/03 19:10:47 Trace: [push] retrying connection in 17735ms
02/03 19:10:47 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:22050}
02/03 19:10:48 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:22050}
02/03 19:10:48 Trace: [transport/raatclient] GOT [13] {“status”:“Dropout”,“samples”:22050}
02/03 19:10:48 Warn: [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
02/03 19:10:48 Trace: [zoneplayer/raat] too many dropouts. stopping stream
02/03 19:10:48 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
02/03 19:10:48 Trace: [zoneplayer/raat] Endpoint Default Output State Changed: Playing => Prepared
02/03 19:10:48 Trace: [transport/raatclient] SENT [15]{“request”:“end_stream”}
02/03 19:10:48 Warn: Track Stopped Due to Slow Media
02/03 19:10:48 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
02/03 19:10:49 Trace: [transport/raatclient] GOT [13] {“status”:“Ended”}
02/03 19:10:49 Trace: [transport/raatclient] GOT [15] {“status”:“Success”}
02/03 19:10:53 Trace: [zone] no playback for 5s, suspending to release audio device
02/03 19:10:53 Trace: [zone] [Nexus 6] Suspend
02/03 19:10:53 Trace: [zone] [Nexus 6] Stop
02/03 19:10:56 Info: [stats] 5756mb Virtual, 1459mb Physical, 562mb Managed, 0 Handles, 65 Threads
02/03 19:10:58 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
02/03 19:10:58 Trace: [push] connecting to 54.81.161.251:9200
02/03 19:10:58 Trace: [push] connected
[/code]

HQ Player:

02/03 19:13:52 Trace: [zone] [Desktop HQPlayer] Playing 1 Items
02/03 19:13:52 Trace: [zone] [Desktop HQPlayer] Selecting Source
02/03 19:13:52 Trace: [hqplayer] [10.0.0.107] Connected
02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><Stop />

02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><Stop result="OK"/>
02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><Status subscribe="1" />

02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><Status state="0" track="0" min="0" sec="0" clips="0"/>
02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><VolumeRange />

02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><VolumeRange enabled="1" min="-60" max="-4"/>
02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><State />

02/03 19:13:52 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><State state="0" mode="0" filter="6" shaper="6" rate="0" volume="-4" active_mode="0" active_rate="0"/>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><State />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><State state="0" mode="0" filter="6" shaper="6" rate="0" volume="-4" active_mode="0" active_rate="0"/>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><VolumeRange />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><VolumeRange enabled="1" min="-60" max="-4"/>
02/03 19:13:53 Info: Invoking 1 connect callbacks with Success
02/03 19:13:53 Trace: [zone] [Desktop HQPlayer] Playing TransportItem
02/03 19:13:53 Trace: [musicpowerstate] music is playing, preventing idle sleep
02/03 19:13:53 Info: [zoneplayer/base] Playing: /mnt/RoonStorage_32234e3ce80f1d8a4d4813b2d5baadf2ef7712f9/Enya/And Winter Came.._/01 And Winter Came....flac
02/03 19:13:53 Trace: [dbperf] flush 0 bytes, 0 ops in 7 ms (cumulative 51163921 bytes, 23509 ops in 35408 ms)
02/03 19:13:53 Info: [zoneplayer/base]     Open Result (Playing):Result[Status=Success]
02/03 19:13:53 Info: [zoneplayer/base] Starting playback
02/03 19:13:53 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 44100/16/2 BitRate=694 Quality=Lossless
    Output OutputType=HQPlayer Quality=Lossless
------------------------------------------------------------

02/03 19:13:53 Trace: [zoneplayer/hqplayer] In StartStream Format=StreamParams[SampleRate=44100, BitsPerSample=16, Channels=2, Layout=CH_2_0 FrameCount=8636544] State=Stopped
02/03 19:13:53 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/195 sec
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><PlaylistClear />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><PlaylistClear result="OK"/>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><PlaylistAdd uri="http://10.0.0.101:9102/7801136f273140b6bdcf8e784f09fb4f/stream.raw" queued="0" />

02/03 19:13:53 Info: [zoneplayer/hqplayer] GOT REQUEST Head /7801136f273140b6bdcf8e784f09fb4f/stream.raw
02/03 19:13:53 Trace: [zoneplayer/hqplayer] request matches current stream
02/03 19:13:53 Info: [zoneplayer/hqplayer] GOT REQUEST Head /7801136f273140b6bdcf8e784f09fb4f/stream.raw
02/03 19:13:53 Trace: [zoneplayer/hqplayer] request matches current stream
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><PlaylistAdd result="OK"/>
02/03 19:13:53 Trace: [zoneplayer/hqplayer] State transition Stopped => WaitForHttp
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><Play />

02/03 19:13:53 Info: [zoneplayer/hqplayer] GOT REQUEST Head /7801136f273140b6bdcf8e784f09fb4f/stream.raw
02/03 19:13:53 Trace: [zoneplayer/hqplayer] request matches current stream
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><Play result="OK"/>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><GetModes />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><GetModes><ModesItem index="0" name="Auto" value="-1"/><ModesItem index="1" name="PCM" value="0"/></GetModes>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><GetFilters />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><GetFilters><FiltersItem index="0" name="none" value="0"/><FiltersItem index="1" name="IIR" value="1"/><FiltersItem index="2" name="FIR" value="2"/><FiltersItem index="3" name="asymFIR" value="3"/><FiltersItem index="4" name="minphaseFIR" value="4"/><FiltersItem index="5" name="FFT" value="5"/><FiltersItem index="6" name="poly-sinc" value="6"/><FiltersItem index="7" name="poly-sinc-mp" value="7"/><FiltersItem index="8" name="poly-sinc-short" value="8"/><FiltersItem index="9" name="poly-sinc-short-mp" value="9"/><FiltersItem index="10" name="poly-sinc-hb" value="14"/><FiltersItem index="11" name="poly-sinc-ext" value="15"/><FiltersItem index="12" name="poly-sinc-mqa" value="18"/><FiltersItem index="13" name="poly-sinc-mqa-mp" value="19"/><FiltersItem index="14" name="sinc" value="10"/><FiltersItem index="15" name="polynomial-1" value="11"/><FiltersItem index="16" name="polynomial-2" value="12"/><FiltersItem index="17" name="minringFIR" value="13"/><FiltersItem index="18" name="minringFIR-mp" value="20"/><FiltersItem index="19" name="closed-form" value="16"/><FiltersItem index="20" name="closed-form-fast" value="17"/></GetFilters>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><GetShapers />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><GetShapers><ShapersItem index="0" name="none" value="0"/><ShapersItem index="1" name="NS1" value="1"/><ShapersItem index="2" name="NS4" value="2"/><ShapersItem index="3" name="NS5" value="7"/><ShapersItem index="4" name="NS9" value="3"/><ShapersItem index="5" name="RPDF" value="4"/><ShapersItem index="6" name="TPDF" value="5"/><ShapersItem index="7" name="Gauss1" value="8"/><ShapersItem index="8" name="shaped" value="6"/></GetShapers>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><GetRates />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><GetRates><RatesItem index="0" rate="0"/><RatesItem index="1" rate="32000"/><RatesItem index="2" rate="44100"/><RatesItem index="3" rate="48000"/><RatesItem index="4" rate="88200"/><RatesItem index="5" rate="96000"/><RatesItem index="6" rate="176400"/><RatesItem index="7" rate="192000"/><RatesItem index="8" rate="352800"/><RatesItem index="9" rate="384000"/><RatesItem index="10" rate="705600"/><RatesItem index="11" rate="768000"/></GetRates>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><VolumeRange />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><VolumeRange enabled="1" min="-60" max="-4"/>
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] SENT <?xml version="1.0" encoding="utf-8"?><State />

02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT <?xml version="1.0" encoding="UTF-8"?><State state="2" mode="0" filter="6" shaper="6" rate="0" volume="-4" active_mode="0" active_rate="44100"/>
02/03 19:13:53 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Enhanced
Elements:
    Source Format=Flac 44100/16/2 BitRate=694 Quality=Lossless
    Output OutputType=HQPlayer Quality=Lossless
    HQPlayer filter=poly-sinc, shaper=TPDF, mode=PCM, rate=44100
------------------------------------------------------------

02/03 19:13:53 Info: [zoneplayer/hqplayer] GOT REQUEST Get /7801136f273140b6bdcf8e784f09fb4f/stream.raw
02/03 19:13:53 Trace: [zoneplayer/hqplayer] request matches current stream
02/03 19:13:53 Trace: [zoneplayer/hqplayer] State transition WaitForHttp => WaitForFirstTimeChange
02/03 19:13:53 Trace: [prebuffer] status 441000/441000 (100%) @ 0/195 sec
02/03 19:13:53 Trace: [hqp/client] [10.0.0.107:4321] GOT UNSOLICITED <?xml version="1.0" encoding="UTF-8"?><Status state="2" track="1" min="0" sec="0" clips="0"/>
02/03 19:13:54 Trace: [hqp/client] [10.0.0.107:4321] GOT UNSOLICITED <?xml version="1.0" encoding="UTF-8"?><Status state="2" track="1" min="0" sec="1" clips="0"/>
02/03 19:13:54 Trace: [zoneplayer/hqplayer] State transition WaitForFirstTimeChange => Playing
2

Are jumbo frames enabled anywhere on your network? If so – can you disable them?

3

Is the Roon Core still performing audio analysis, if so as a test try disabling it?

4

No Jumbo frames utilized in my network.

I did try turning off background audio analysis (mainly to clean up the logs), the problem unfortunately still persists with it off.

5

Thanks for the updates, I’m leaving a tag for @support to follow up with you.

6

Those logs don’t look like a performance problem–they look like no audio packets at all are making from your core to the endpoints.

I know jumbo frames/MTU have already been mentioned–it really is the most common cause of your exact symptoms. It’s really not that uncommon to have this stuff turned on by mistake on a NAS, and it would totally explain this. Worth double-checking. Other than that, look for firewall/networking type issues at/around the NAS.

I don’t think anything really changed about how RAAT streams audio from 1.2 -> 1.3–at least nothing that I’d expect to cause this pattern of symptoms.

7

Thanks for the help guys. I did just resolve this one. My NAS has 2 GigE NICs which normally operate independently, but I had them configured as a bonded interface. When I deleted the bond and utilized them as 2 separate NICs again the problem has gone away. I’m a bit perplexed how this changed from version 1.2 or why connecting out to the HQPlayer endpoint works fine, but I think my situation was an edge case given my unusual configuration.