Intermittent Playback Issues with Roon on Synology NAS Setup (ref#181OHC)

What best describes your playback issue?

· Music stops playing unexpectedly

What type of Zone is affected by this problem?

· *All of my Zones* are affected.

Does the issue affect all file formats?

· The issue affects *multiple/all* file formats.

Does the issue happen with local library music, streaming service music, or both?

· *Both streaming and local* *library* music are affected.

Do you encounter any playback errors with the "System Output" Zone?

· I don't have a System Output available, but I'd like to keep troubleshooting

How is the affected Zone connected to your RoonServer machine?

· Network - Ethernet

Which network audio protocol is the Zone using with Roon?

· Airplay

Since this is an Airplay Zone, have you tried toggling Airplay Compatibility Mode for this Zone in the Device Setup window?

· There is no change in behavior.

Does the device show up at all in Roon Settings -> Audio?

· The Zone is listed under the correct protocol

Does the device play audio from another source when using the same connection?

· The device has no problems with another audio source

Have you checked that Roon is whitelisted in any firewalls?

· I've checked the firewall and the issue remains

If the device has multiple output options, do the other options work as expected?

· Multiple output types are affected

Is the device using the latest firmware as per the manufacturer?

· Firmware is up-to-date but the issue remains

What are the make and model of the affected audio device(s) and the connection type?

· Marantz SR8015, Denon Home Soundbar 550

Describe the issue

With me, Roon is unable to play a queue without interruptions....
After an earlier trial, I took the plunge and signed up for a one-year subscription. Unfortunately, it is proving to be a disappointing experience to this point.
My setup:
Roon server running on a Synology DS918+ NAS. Now in a docker, but I've also tried the separate app (.pkg).
Marantz SR8015 AVR.
In two rooms a Denon Home Sound Bar 550 .
In the bathroom Denon Home 150 .
Gigabit internet connection.
All equipment ethernet connected with CAT5e or CAT6 cables, except the Denon Home 150
I don't use any of the MUSE settings, the only thing enabled is sound normalisation.
The good:
The sound quality.
The sound customisation options (but which I am not yet confident to use).
The app and user features and usability.
The disappointment:
That only the Denon Home 150 is ROON Ready, but I could have checked this out myself before buying. My bad.
The random stopping of the queue, which sometimes resumes after x-minutes and usually not at all. Regardless of where the music is being played at the time. This always happens when playing music from my library, all flac files. Most 16bit, sometimes a higher bitrate. But also when playing a queue with TIDAL, although less frequently.
I have read more posts on this forum about this problem and usually the cause is dedicated to a bad network connection. That cannot be the culprit in my case.
Also, the Synology has enough memory and the processor does not have to work hard during music playback I see in the user interface.
At the moment, it's a mystery to me why ROON can't keep playing normally, while playback via, say, Plex Amp or directly via the TIDAL app is completely trouble-free.
Have I spent my money on something that is not quite ready? I had a higher expectation of ROON and, as I said, I am hugely regretting my purchase at this point.
What could be the problem here?

Edit: This morning I started a playlist and the issue occurred straight away. I have that part of the log available. I don’t see an option to upload it here, so let me know how you want to receive it.
Edit2: And also the part of the log where TIDAL playback suddenly stopped is available.

Describe your network setup

Ubiquiti AmpliFi AFi-HD router. All affected devices connected with CAT5e or CAT6 ethernet cables

AirPlay playback via Roon can have its own issues based upon the firmware of the receiving devices. I personally would never recommend Roon to anyone who is only going to use it for AirPlay playback because you will never get bit perfect playback on any content higher than 44/16. The best test of your system is to see if it can play to a proper Roon endpoint using the RAAT protocol. Do you have any endpoints that you could test that way? If not, I recommend checking for firmware updates and/or technical support for your AirPlay endpoints from their respective vendors.

I’m having very similar issues on my Synology DS-918+ (non-Docker image). It’s connected to wired GB ethernet and my internet connection is 1 Gbps fiber (tested at 950 Mbps). Router is Asus RT-AX86U.

From my local library songs sometimes play but eventually start skipping and moving to next tracks, which don’t play and next track again until it completely stops.

Also both Tidal and Qobuz are constantly giving “loading slowing messages” and basically doing the same thing… I have DAPs and other players that are working perfectly with streaming services, only Roon is giving me problems.

I can stream anything else from the internet even at 4k video resolution with no issues at all, so I can’t imagine internet speed is an issue.

Also, note that Audirvana is not having any of these issues, using the same library and streaming sources.

As I mentioned, the Denon Home 150 is ROON Ready, but gives the same issues as the Airplay devices. Both with music from my own library and with TIDAL.

All devices have current firmware.

I also tried the RHEOS extension that makes use of the Squeezebox functionality and not AirPlay. No change in behavior though.

My guess it’s a server side issue, but my NAS doesn’t seem to struggle with ROON server so I’m unable to figure out what the issue is here.

Ok. In your original post, you only mentioned AirPlay, so that’s a premise I was going on.

FWIW, Roon doesn’t support Docker installations, so in order to ensure support, you will probably need to go back to the Roon on NAS package for the time being, even though it seems to present the same issues. Remember to install the ffmpeg package per the Roon on NAS instructions as well…

Thanks for your reply DDPS. Installed ROON as .pkg again and also added the ffmpeg package. Give it a try again for the next couple of hours and report the result back here.

1 Like

Excellent. Probably best to restart Roon server after installing the ffmpeg package so that it picks the new package up at launch FYI…

Hi @Robbert_Marrink,
Thanks for reaching out to us about this. Let us know how this setup works out for you.

So far it seemed to be fine, however, the problem seemed to occur again after about an hour. The log shows “[prebuffer] sleeping in read – this isn’t good”.

I’ll see if this happens again or more.

How are your power settings configured under Settings > Hardware & Power?

No power schedule. The rest as in the screenshots.


By the way, the moment my music stopped I was playing a playlist from TIDAL, not from my own files.

And there it happened again. At 16:19 it stopped and had to press play again to continue the TIDAL cue.

10/22 16:19:57 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Output OutputType=AirPlay Quality=HighQuality SubType=2 Model=Denon Home Sound Bar 550
------------------------------------------------------------
10/22 16:19:57 Info: [zone Werkkamer + Slaapkamer] OnPlayFeedback StoppedEndOfMediaNatural
10/22 16:19:57 Debug: [zone Werkkamer + Slaapkamer] _Advance (Track)
10/22 16:19:57 Trace: [library] finished with 2 dirty tracks 1 dirty albums 3 dirty performers 2 dirty works 2 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 9 changed objects
10/22 16:19:57 Trace: [music/searchindex] [search-index]  removed in 0ms: 1 albums, 0 tracks, 0 works, 0 performers, 0 labels, 0 genres
10/22 16:19:57 Trace: [music/searchindex] [search-index] added in 3ms: 1 albums, 0 tracks, 0 works, 0 performers, 0 labels, 0 genres
10/22 16:19:57 Trace: [music/searchindex] totals: 2258 albums, 24872 tracks, 16067 works, 33484 performers, 932 labels, 1314 genres
10/22 16:19:57 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:19:57 Trace: computing profile stats
10/22 16:19:57 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:19:57 Trace: done computing profile stats
10/22 16:19:57 Trace: [roondns] flushed 8 last-known-good entries
10/22 16:19:57 Debug: [easyhttp] [1510] POST to http://ws.audioscrobbler.com/2.0/ returned after 158 ms, status code: 200, request body size: 252 B
10/22 16:19:57 Debug: [music/profilestats] Computed stats in 137ms, got 50 top albums, 50 top performers, 64 genres
10/22 16:19:57 Trace: GetImageData[Remote](id=12722 spec=500 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/500.jpg) => fetched in 687ms status=200 size=148127 overalltime=690ms
10/22 16:19:58 Trace: GetImageData[Remote](id=12722 spec=500 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/500.jpg) => fetched in 751ms status=200 size=148127 overalltime=765ms
10/22 16:19:58 Info: [stats] 6836mb Virtual, 1472mb Physical, 784mb Managed, 379 Handles, 82 Threads
10/22 16:19:58 Trace: GetImageData[Remote](id=12722 spec=256 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/256.jpg) => fetched in 1282ms status=200 size=43004 overalltime=1297ms
10/22 16:19:58 Trace: GetImageData[Remote](id=12722 spec=512 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/512.jpg) => fetched in 1449ms status=200 size=153798 overalltime=1451ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002).  re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002).  re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002).  re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002).  re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4).  re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4).  re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4).  re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4).  re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4).  re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:01 Trace: [dbperf] flush 0 bytes, 0 ops in 13 ms (cumulative 0 bytes, 0 ops in 33750 ms)
10/22 16:20:01 Trace: [library] endmutation in 4481ms
10/22 16:20:01 Trace: [library/playlist] saving playlist folder 10:1:2fbd43db-3328-4514-91b5-3303e447c95c [root]
10/22 16:20:01 Trace: finished updating 4 dirty playlists and 1 dirty playlist folders
10/22 16:20:01 Trace: [library] endmutation in 25ms
10/22 16:20:01 Trace:  [broker/accounts]   doing auth refresh in EnsureAuthReady, next=10/22/2024 15:20:06
10/22 16:20:01 Trace: [broker/accounts] refreshing account info for email='roma@cybertown.nl' userid=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:01 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
10/22 16:20:01 Trace: [fiveaccountserver] BODY token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523
10/22 16:20:01 Trace: [dbperf] flush 0 bytes, 0 ops in 10 ms (cumulative 0 bytes, 0 ops in 33760 ms)
10/22 16:20:01 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 0 bytes, 0 ops in 33762 ms)
10/22 16:20:01 Trace: [roondns] flushed 8 last-known-good entries
10/22 16:20:01 Debug: [easyhttp] [1515] POST to https://api.roonlabs.net/accounts5/accounts/3/login returned after 239 ms, status code: 200, request body size: 42 B
10/22 16:20:01 Trace: [fiveaccountserver] GOT {"status":"Success","userid":"779e78f9-be8d-4cc4-af2f-98515f276a14","token":"eb0fee49-e0fa-4a25-a02c-b7a5b5030523","expiration":30,"email":"roma@cybertown.nl","groups":[]}
10/22 16:20:02 Debug: [easyhttp] [1517] GET to https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 306 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:20:02 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/profileslist?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523
10/22 16:20:02 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/userinfo?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523&machineid=cc1c8cb4-595e-b691-576b-aac45cf5d025
10/22 16:20:02 Trace: [broker/accounts] updated token. New expiration is 11/21/2024 16:20:06
10/22 16:20:02 Debug: [easyhttp] [1516] GET to https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 389 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Trace: [bits] myinfo: {"pushid":"broker/bd2dc65f-2d22-4a23-9f00-c5377233eda5","roon_auth_token":"eb0fee49-e0fa-4a25-a02c-b7a5b5030523","os":"Linux 4.4.180+","platform":"linuxx64","machineversion":200001470,"branch":"production","appmodifier":"","appname":"RoonServer"}
10/22 16:20:02 Trace: [airplay/client] Sending TEARDOWN
10/22 16:20:02 Trace: [airplay/client] Sending TEARDOWN
10/22 16:20:02 Debug: [easyhttp] [1519] GET to https://api.tidal.com/v1/tracks/117439337?countryCode=NL returned after 151 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337?countryCode=NL => Success
10/22 16:20:02 Trace: [airplay/client] TEARDOWN was successful
10/22 16:20:02 Info: [airplay] AirPlay device disconnected: AirPlayDevice[DeviceId=C2F85B428C22@Werkkamer._raop._tcp.local, Name=Werkkamer-.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.169:7000]
10/22 16:20:02 Trace: [airplay] disconnected
10/22 16:20:02 Trace: [zone] Werkkamer received transport control from endpoint integration: suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Werkkamer + Slaapkamer received transport control from Werkkamer (Denon Home Sound Bar 550): suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Suspend
10/22 16:20:02 Info: [zone Werkkamer + Slaapkamer] OnPlayFeedback Stopped
10/22 16:20:02 Info: [zone Werkkamer + Slaapkamer] Canceling Pending Sleep
10/22 16:20:02 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:20:02 Trace: [airplay/client] Sending FLUSH
10/22 16:20:02 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [STOPPED @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:20:02 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
10/22 16:20:02 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [STOPPED @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:20:02 Debug: [easyhttp] [1522] GET to https://api.tidal.com/v1/tracks/45409877?countryCode=NL returned after 152 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877?countryCode=NL => Success
10/22 16:20:02 Debug: [easyhttp] [1520] GET to https://api.roonlabs.net/accounts5/accounts/3/profileslist?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523 returned after 173 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [fiveaccountserver] GOT {"status":"Success","profiles":[{"id":"d2cc3a92-385f-4afa-b6f8-f2b57c6aea2f","name":"Robbert","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-779e78f9-be8d-4cc4-af2f-98515f276a14--d2cc3a92-385f-4afa-b6f8-f2b57c6aea2f.jpg","birthdate":"19650308","scrobbler_token":"39x1ejdTWKJyJahmuaRhRbLAt1F3VJ1X","facebook_auth":"{ \"accesstoken\": \"EAADeu8DcOV8BAEwckLFZBwqpyfSZCSucb9ouK4SVbJn6sjmukfuwHAnSk1ACqgY7PWXmZBslMGEL1ZAE9kKeqB0Yv2AMwzXjPYYsGDDDAQoZAQRVcVXZBhgnySFkpTdPl2Ncbq0TPRjBjRzLINvNj1xZAf2gsKBhDEmng7bKUOguf9RZCztTZCJqh\" }"}]}
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Debug: [easyhttp] [1521] GET to https://api.roonlabs.net/accounts5/accounts/3/userinfo?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523&machineid=cc1c8cb4-595e-b691-576b-aac45cf5d025 returned after 183 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [fiveaccountserver] GOT {"user":{"tfa":{"enabled":true},"userid":"779e78f9-be8d-4cc4-af2f-98515f276a14","firstname":"Robbert","lastname":"Marrink","email":"roma@cybertown.nl","joinmailinglist":false,"allowpushnotifications":true,"class":"Normal","groups":[],"dncs":["company_news","roon_software_news","roon_software_promos","roon_store_promos"],"trialallowed":false},"status":"Success"}
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Trace: [airplay/client] TEARDOWN was successful
10/22 16:20:02 Info: [airplay] AirPlay device disconnected: AirPlayDevice[DeviceId=FADE9C8D6810@Slaapkamer._raop._tcp.local, Name=Slaapkamer-2.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.226:7000]
10/22 16:20:02 Trace: [airplay] disconnected
10/22 16:20:02 Trace: [zone] Slaapkamer received transport control from endpoint integration: suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Werkkamer + Slaapkamer received transport control from Slaapkamer (Denon Home Sound Bar 550): suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Suspend
10/22 16:20:02 Info: [zone Werkkamer + Slaapkamer] Canceling Pending Sleep
10/22 16:20:02 Warn: [airplay/client] FLUSH failed: 455 Method Not Valid In This State
10/22 16:20:02 Debug: [easyhttp] [1526] GET to https://api.roonlabs.net/metadata/1/albums/166:0:45409871/credits?c=tidal-nl&tidal=max returned after 162 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Debug: [easyhttp] [1527] GET to https://api.roonlabs.net/metadata/1/albums/166:0:45409871?c=tidal-nl&tidal=max returned after 168 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Debug: [easyhttp] [1525] GET to https://api.roonlabs.net/metadata/1/albums/166:0:45409871/tracks?c=tidal-nl&tidal=max returned after 184 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Info: [metadata/albumdetails] Created album details screen for a6003435343039383731
10/22 16:20:02 Info: [metadata/albumdetails] 79004d5730303032383432393039,3e01e996cf1975dbd643a6d3e4266e4b8319,3e010b5f1f348a182f47994e1c18147fa0f2,79004d5230303034343038363437,a6003435343039383731,c80030383836343435323035353033,cd0054595f52774477504f523751687578626255
10/22 16:20:02 Debug: [easyhttp] [1523] POST to https://api.roonlabs.net/bits/1/q/roon.base.,roon.internet_discovery.,roon.debug.,roon.broker.,roon.dsp.,roon.sood.?roon_auth_token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523 returned after 394 ms, status code: 200, request body size: 246 B
10/22 16:20:02 Trace: [bits] updated bits, in 402ms
10/22 16:20:02 Debug: [easyhttp] [1524] GET to https://api.roonlabs.net/profileimages/five-779e78f9-be8d-4cc4-af2f-98515f276a14--d2cc3a92-385f-4afa-b6f8-f2b57c6aea2f.jpg?random=7c36b0db-2647-4695-8a56-325dd30864dc returned after 332 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Debug: [easyhttp] [1530] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:1291077&type=biography&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 126 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 0 bytes, 0 ops in 33763 ms)
10/22 16:20:02 Debug: [easyhttp] [1528] POST to https://api.roonlabs.net/metadata/1/albums/translate?c=tidal-nl&tidal=max returned after 207 ms, status code: 200, request body size: 192 B
10/22 16:20:02 Trace: [library] endmutation in 12ms
10/22 16:20:02 Info: [library/albumdetails] found 1 streaming service alternates
10/22 16:20:03 Debug: [easyhttp] [1529] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=121:0:MW0002842909&type=review&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 216 ms, status code: 200, request body size: 0 B
10/22 16:20:03 Debug: [easyhttp] [1531] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:1271047&type=biography&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 217 ms, status code: 200, request body size: 0 B
10/22 16:20:03 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 0 bytes, 0 ops in 33765 ms)
10/22 16:20:03 Trace: GetImageData[Remote](id=4398 spec=16by7_2048 key=engaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303032383433373434/photo/16by7_2048.jpg) => fetched in 201ms status=200 size=259452 overalltime=235ms
10/22 16:20:03 Debug: [easyhttp] [1532] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:3900902&type=biography&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 280 ms, status code: 200, request body size: 0 B
10/22 16:20:03 Trace: GetImageData[Remote](id=4397 spec=1by1_1024 key=dngaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303032383433373434/avatar/1by1_1024.jpg) => fetched in 794ms status=200 size=244783 overalltime=841ms
10/22 16:20:12 Debug: [easyhttp] [1535] POST to https://api.roonlabs.net/device-map/1/register returned after 177 ms, status code: 200, request body size: 6 KB
10/22 16:20:12 Trace: [devicemap] device map updated
10/22 16:20:13 Info: [stats] 6788mb Virtual, 1472mb Physical, 795mb Managed, 365 Handles, 72 Threads
10/22 16:20:14 Trace: [library] endmutation in 12ms
10/22 16:20:18 Trace: [library] endmutation in 11ms
10/22 16:20:23 Trace: [library] endmutation in 11ms
10/22 16:20:24 Trace: [library] endmutation in 11ms
10/22 16:20:24 Trace: [library] endmutation in 12ms
10/22 16:20:24 Trace: [library] endmutation in 11ms
10/22 16:20:26 Trace: GetImageData[Remote](id=4574 spec=16by7_2048 key=ytgaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303030373737373835/photo/16by7_2048.jpg) => fetched in 223ms status=200 size=99474 overalltime=238ms
10/22 16:20:26 Trace: GetImageData[Remote](id=4573 spec=1by1_1024 key=xtgaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303030373737373835/avatar/1by1_1024.jpg) => fetched in 262ms status=200 size=132405 overalltime=292ms
10/22 16:20:28 Info: [stats] 6748mb Virtual, 1472mb Physical, 797mb Managed, 365 Handles, 67 Threads
10/22 16:20:30 Trace: [library] endmutation in 12ms
10/22 16:20:36 Trace: [library] endmutation in 12ms
10/22 16:20:43 Info: [stats] 6748mb Virtual, 1472mb Physical, 797mb Managed, 365 Handles, 68 Threads
10/22 16:20:53 Trace: [library] endmutation in 11ms
10/22 16:20:54 Trace: [library] endmutation in 11ms
10/22 16:20:54 Trace: [library] endmutation in 12ms
10/22 16:20:54 Trace: [library] endmutation in 11ms
10/22 16:20:54 Trace: [library] endmutation in 11ms
10/22 16:20:58 Info: [stats] 6788mb Virtual, 1472mb Physical, 765mb Managed, 365 Handles, 76 Threads
10/22 16:21:13 Info: [stats] 6764mb Virtual, 1472mb Physical, 765mb Managed, 365 Handles, 69 Threads
10/22 16:21:20 Trace: [library] endmutation in 11ms
10/22 16:21:24 Trace: [library] endmutation in 13ms
10/22 16:21:24 Trace: [library] endmutation in 11ms
10/22 16:21:24 Trace: [library] endmutation in 12ms
10/22 16:21:24 Trace: [library] endmutation in 11ms
10/22 16:21:28 Info: [stats] 6756mb Virtual, 1472mb Physical, 766mb Managed, 365 Handles, 68 Threads
10/22 16:21:29 Trace: [library] endmutation in 11ms
10/22 16:21:43 Info: [stats] 6740mb Virtual, 1472mb Physical, 766mb Managed, 365 Handles, 70 Threads
10/22 16:21:58 Info: [stats] 6892mb Virtual, 1472mb Physical, 767mb Managed, 365 Handles, 89 Threads
10/22 16:22:13 Info: [stats] 6756mb Virtual, 1472mb Physical, 767mb Managed, 365 Handles, 68 Threads
10/22 16:22:14 Trace: [library] endmutation in 11ms
10/22 16:22:17 Trace: [zone Werkkamer + Slaapkamer] PlayPause
10/22 16:22:17 Trace: [zone Werkkamer + Slaapkamer] Selecting Source state=Stopped
10/22 16:22:17 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:17 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:17 Debug: [easyhttp] [1540] GET to https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 167 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:22:17 Debug: [easyhttp] [1539] GET to https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 173 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:22:17 Debug: [easyhttp] [1541] GET to https://api.tidal.com/v1/tracks/117439337?countryCode=NL returned after 51 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337?countryCode=NL => Success
10/22 16:22:17 Debug: [easyhttp] [1542] GET to https://api.tidal.com/v1/tracks/45409877?countryCode=NL returned after 46 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877?countryCode=NL => Success
10/22 16:22:17 Info: [Werkkamer + Slaapkamer] [zoneplayer] Playing: https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZmFkYzViYjlhMTNmZDllNTE5Yjc5ZTFmYTM5ZmExYV82MS5tcDQ/0.flac
10/22 16:22:17 Info: [Werkkamer + Slaapkamer] [zoneplayer] Queueing: https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKAgDEiQ3NGMwODQ1ZmNkMTk0NmIwMTYwZjg3ZTA5Y2I0ZTEyZi5tcDQ/0.flac
10/22 16:22:17 Info: FTMSI-B new FileCache ti/93C7735A
>>> /var/packages/RoonServer/shares/RoonServer/RoonOnNAS/RoonServer/Cache/smc.db/bfc/58.cache
>>> https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZmFkYzViYjlhMTNmZDllNTE5Yjc5ZTFmYTM5ZmExYV82MS5tcDQ/0.flac
10/22 16:22:17 Debug: FTMSI-B Cache open file ti/93C7735A domain: zoneplayer:7 ordinal:11
10/22 16:22:17 Debug: FTMSI-B ti/93C7735A download status: DownloadNotStarted accessTimeout:False openFiles:1 prev:no
10/22 16:22:17 Info: FTMSI-B ti/93C7735A: allocated bw changed from 0 to 51200 kbps
10/22 16:22:17 Info: FTMSI-B 1 FileCache ti/93C7735A dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':11) --> bw limit:51200kbps
10/22 16:22:17 Info: FTMSI-B 2 FileCache ti/873605B6 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':74) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B 3 FileCache ti/BDD8D5DD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':75) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B 4 FileCache ti/6BDF080C dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:7':9) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B 5 FileCache ti/D456F5C5 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:7':10) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B FileCache removed ti/6BDF080C
10/22 16:22:17 Debug: FTMSI-B-OE ti/6BDF080C exit thread signalled
10/22 16:22:17 Trace: FTMSI-B 1 FileCache ti/93C7735A dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':11) --> bw limit:51200kbps
10/22 16:22:17 Trace: FTMSI-B 2 FileCache ti/873605B6 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':74) --> bw limit:0kbps
10/22 16:22:17 Debug: FTMSI-B-OE ti/6BDF080C exit thread signalled
10/22 16:22:17 Trace: FTMSI-B 3 FileCache ti/BDD8D5DD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':75) --> bw limit:0kbps
10/22 16:22:17 Trace: FTMSI-B 4 FileCache ti/D456F5C5 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:7':10) --> bw limit:0kbps
10/22 16:22:17 Debug: FTMSI-B-OE ti/93C7735A created new req 1 for block 0 p 4294967295; active requests 1
10/22 16:22:17 Info: FTMSI-B new FileCache ti/D879AAF7
>>> /var/packages/RoonServer/shares/RoonServer/RoonOnNAS/RoonServer/Cache/smc.db/bfc/59.cache
>>> https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKAgDEiQ3NGMwODQ1ZmNkMTk0NmIwMTYwZjg3ZTA5Y2I0ZTEyZi5tcDQ/0.flac
10/22 16:22:17 Debug: FTMSI-B Cache open file ti/D879AAF7 domain: zoneplayer:7 ordinal:12
10/22 16:22:17 Debug: FTMSI-B ti/D879AAF7 download status: DownloadNotStarted accessTimeout:False openFiles:1 prev:no
10/22 16:22:17 Info: FTMSI-B FileCache removed ti/D456F5C5
10/22 16:22:17 Debug: FTMSI-B-OE ti/D456F5C5 exit thread signalled
10/22 16:22:17 Trace: FTMSI-B 1 FileCache ti/93C7735A dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':11) --> bw limit:51200kbps
10/22 16:22:17 Trace: FTMSI-B 2 FileCache ti/873605B6 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':74) --> bw limit:0kbps
10/22 16:22:17 Trace: FTMSI-B 3 FileCache ti/BDD8D5DD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':75) --> bw limit:0kbps
10/22 16:22:17 Trace: FTMSI-B 4 FileCache ti/D879AAF7 dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':12) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B FileCache disposed ti/6BDF080C
10/22 16:22:17 Debug: FTMSI-B-OE ti/D456F5C5 exit thread signalled
10/22 16:22:17 Info: FTMSI-B FileCache disposed ti/D456F5C5
10/22 16:22:18 Debug: [easyhttp] [1543] GET to https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZmFkYzViYjlhMTNmZDllNTE5Yjc5ZTFmYTM5ZmExYV82MS5tcDQ/0.flac?token=1729610406~YmMzNGU1OTQxNTNmM2FjYzZjYWM2ZDdiNmUyM2E4MjVjZDFmNjZhMQ== returned after 126 ms, status code: 200, request body size: 0 B
10/22 16:22:18 Debug: FTMSI-B got length for ti/93C7735A; 40.4 MBytes
10/22 16:22:18 Debug: FTMSI-B ti/93C7735A download status: FileLengthRetrieved accessTimeout:False openFiles:1 prev:(DownloadNotStarted,False,1)
10/22 16:22:18 Debug: FTMSI-B-OE set min bandwidth for ti/93C7735A to 872 kbps
10/22 16:22:18 Info: FTMSI-B-OE ti/93C7735A rid:1 response took 127ms
10/22 16:22:18 Debug: FTMSI-B ti/93C7735A download status: FirstBlockRetrieved accessTimeout:False openFiles:1 prev:(FileLengthRetrieved,False,1)
10/22 16:22:18 Info: [Werkkamer + Slaapkamer] [zoneplayer]     Open Result (Playing):Result[Status=Success]
10/22 16:22:18 Info: [Werkkamer + Slaapkamer] [zoneplayer] Starting playback
10/22 16:22:18 Trace: [airplay/client] Connecting to airplay server
10/22 16:22:18 Trace: [airplay/client] Connecting to airplay server
10/22 16:22:18 Debug: Lastfm '39x1ejdTWKJyJahmuaRhRbLAt1F3VJ1X' START: The Chris Robinson Brotherhood - Burn Slow 
10/22 16:22:18 Info: [zone Werkkamer + Slaapkamer] OnPlayFeedback Playing
10/22 16:22:18 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:18 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:18 Trace: [airplay/client] Requesting OPTIONS
10/22 16:22:18 Info: [airplay/client] REQUESTING OPTIONS *
10/22 16:22:18 Trace: [airplay/client] Requesting OPTIONS
10/22 16:22:18 Info: [airplay/client] REQUESTING OPTIONS *
10/22 16:22:18 Trace: [airplay/client] Got good OPTIONS: ANNOUNCE, SETUP, RECORD, PAUSE, FLUSH, FLUSHBUFFERED, TEARDOWN, OPTIONS, POST, GET, PUT
10/22 16:22:18 Trace: [airplay] connected
10/22 16:22:18 Info: [airplay] AirPlay device connected: AirPlayDevice[DeviceId=C2F85B428C22@Werkkamer._raop._tcp.local, Name=Werkkamer-.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.169:7000]
10/22 16:22:18 Trace: [airplay/client] Sending ANNOUNCE
10/22 16:22:18 Trace: [airplay/client] Got good OPTIONS: ANNOUNCE, SETUP, RECORD, PAUSE, FLUSH, FLUSHBUFFERED, TEARDOWN, OPTIONS, POST, GET, PUT
10/22 16:22:18 Info: [airplay] AirPlay device connected: AirPlayDevice[DeviceId=FADE9C8D6810@Slaapkamer._raop._tcp.local, Name=Slaapkamer-2.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.226:7000]
10/22 16:22:18 Trace: [airplay] connected
10/22 16:22:18 Trace: [airplay/client] Sending ANNOUNCE
10/22 16:22:18 Trace: [airplay/client] ANNOUNCE Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SETUP
10/22 16:22:18 Trace: [airplay/client] ANNOUNCE Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SETUP
10/22 16:22:18 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/455 sec
10/22 16:22:18 Trace: [airplay/client] SETUP was successful
10/22 16:22:18 Trace: [airplay/client] Sending RECORD
10/22 16:22:18 Trace: [airplay/client] SETUP was successful
10/22 16:22:18 Trace: [airplay/client] Sending RECORD
10/22 16:22:18 Trace: [airplay/client] Record Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SET_PARAMETER volume -24db
10/22 16:22:18 Trace: [airplay/client] Record Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SET_PARAMETER volume -21db
10/22 16:22:18 Debug: [easyhttp] [1544] POST to http://ws.audioscrobbler.com/2.0/ returned after 132 ms, status code: 200, request body size: 292 B
10/22 16:22:18 Trace: [airplay/client] Sending first sync packet rtptime=316741943 synctime=316830143
10/22 16:22:18 Trace: [airplay/client] Sending first sync packet rtptime=177012695 synctime=177100895
10/22 16:22:18 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Output OutputType=AirPlay Quality=HighQuality SubType=2 Model=Denon Home Sound Bar 550
------------------------------------------------------------
10/22 16:22:18 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Output OutputType=AirPlay Quality=HighQuality SubType=2 Model=Denon Home Sound Bar 550
------------------------------------------------------------
10/22 16:22:18 Trace: [mdns/dacp] [airplay/dacp] got command 'setvolume' value=-21
10/22 16:22:18 Trace: [mdns/dacp] [airplay/dacp] got command 'setvolume' value=-24
10/22 16:22:18 Trace: [airplay/client] Sending SET_PARAMETER volume -21db
10/22 16:22:18 Trace: GetImageData[Remote](id=12722 spec=500 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/500.jpg) => fetched in 61ms status=200 size=148127 overalltime=68ms

> Discontinued due to limitation in characters here.

I see a lot of AirPlay activity in there. Are you playing to your Denon Home 150? Is it using RAAT or AirPlay?

Read this for more - it sounds like that device is not really using RAAT, but I am not an expert on this…

I am just a user like you, and I understand it is frustrating that Roon might not be good at supporting all AirPlay devices. It would be really interesting if you could find a way to just play some music through RAAT and see if the problem remains…

Most of the time I play to the Denon Home Soundbar 550. They are not ROON Ready (yet). I hope they will at some point. My Denon Home 150 is in the bathroom. I’ll see if I can change my setup to test with it. It does look like it uses RAAT.

I tried the RHEOS extension that makes them appear as Squeezebox Devices, but presents the same issue at some point.

I expected ROON to have no problems with AirPlay, I had not read anything about that so made the choice to subscribe. Perhaps my Marantz AVR and maybe at some point my Denon Home soundbars would also become ROON Ready. Until then, playback via AirPlay. After all, the setup is ROON tested. But the user experience is very disappointing so far. Would have been better off with a monthly subscription instead of a year :frowning:

Anyway thanks for looking into the problem with me.

Hi @daniel. Is there anything in the part of the log I posted that points in the direction of the problem?

Yep - this is good info. This all falls on Roon staff at this point. I use a Synology NAS with no issues whatsoever, but with other devices receiving (RoPieee and Apple AirPort), and am going to be of limited help from here. Sorry. I will keep an eye here to see if I can help of course!

Interesting setup!

No problem. I really appreciate that you thought along what the problem could be. I just “Sherlock Holmes” further.

One more question, since you are also a Syno user, do you have your firewall on your NAS enabled?

1 Like

I do not. I use a pfSense firewall. You can read about my whole setup here.

1 Like

I’ve played for a long time with RoononNas without any troubles. Now it’s on a Nuc. I happened to have Denon 550 and 150’s as surround for my tv,
They play wireless without any trouble with Roon AirPlay. Although I never use them this way. I got curieus by seeing your signalpath. Do you have Muse activeted? If so turn it of and see if that helps the intermittent playing.

The only thing I use is volume normalization because there is difference in volume between different albums in my library.

Currently, my music plays for about three hours without interruptions. No idea why it seems to be doing better now than before, where I had the first stop within an hour. I do use the Rheos extension now, but in previous use that didn’t seem to give a solution.

But I will take turning off volume normalization into consideration, should things go wrong again.