Millions of times in a row, 100’s of times each 100th of a second. It’s unending, no wonder it’s not responsive.
I deleted all my live radio stations, and it behaved for a while. I tried cleaning up my library (though I don’t think that’s related). If I restart the core, and just use a single controller, I can get it to behave for a while. But then if I go to a different controller, this behavior (and the same pattern in the logs) seems to start again. So if I restart the Roon Server (ROCK) and just use my iPhone, it seems like I can get it to behave for a while. But then it starts up again unexpectedly. When I can get it to play, it seems to continue just fine (especially if it’s local) even if the interface is unresponsive / jellyfish. Sometimes it can’t load the next song from a Qobuz or Roon playlist for a long time when the interface is unresponsive.
I’m at my wits end. I tried to find other support threads which had the same issue, but none of them seem to have resolved.
This one seems similar, and doesn’t seem to have been solved:
Ok, perhaps I fixed this. I reinstalled Roon (early release) on my Mac. I’d done that last night, and it didn’t seem to make a difference. But now it has been stable for 20 minutes. More later.
Thanks for the updates - and nice work performing a fresh reinstall! Let us know how things perform over the next few days and we’ll keep monitoring the thread for your results.
Thanks @Benjamin - will do, and appreciate the quick response. It stymies me why this multiple controller thing can result in the core having endless requests, but I’ll take it if it’s well behaved!
Would be great if there were some kind of error trapping for "constant repeated requests’ and other common patterns that would ‘phone home’ to you guys instead of requiring support requests - but that may be too much to ask
Hi-
I tried to re-open the thread which was marked “solved” and subsequently closer, per your suggestion @noris but I can’t, so hoping you can merge this.
After a number of days of happy simple useage, got the same issue I had previously - endless [radio/query] entries in the logs, and spinning squid. Occasionally I can get through to a screen paint, and occasionally I can start playback. If I disconnect from the ROCK and try to reconnect, I sometimes see that the ROCK Is initializing with a red dot, and sometimes it goes to green dot / ready. So I restart the “Roon Server Software” from the ROCK’s web admin page, and can connect to the ROCK, but still it’s stuck in the same pattern of spinning squid, and if I look at log files it’s again stuck in the Trace: [Broker:Media] [radio/query] performing channel query pattern (see below).
I have no “my live radio” stations, and I do not listen to live radio on Roon. I never even visit that page unless this is happening. The one thing I can think change is that I tried pointing my storage location for my library from my NAS (where it has been) to a local USB, it rescanned library for a few hours, I played with it, and then switched back to the NAS and it rescanned again. No idea why that would get it stuck in a radio query API DOS torrent, but thought I should mention.
There are 15+ full log files that look like the following. Clearly it’s hammering the API looking for something over and over and over again, thousands of times per second. Really, if you look at log files, it’s filling a complete 8.5MB log file every 1-3 minutes with this same pattern. Actually I just checked and at 8:40am ET this morning it filled up 6 complete log files in the space of 1 minute. I included a section where there was some other query getting a word in edgewise, but the vast majority of log files just look like the [radio/query] bit over and over again.
Also, in case it’s helpful, here’s a spot in the logs where the interface seems to be able to “get a word in edgewise” and I can start replay. But the remote interface just keeps repainting over and over again now after I managed to “hit the play button”. You can see that in the space of 3 seconds it goes from unreachable / loop / DOS / busy to “I managed to hit the button” to straight back into the loop:
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Trace: [Broker:Media] [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 0 bytes, 0 ops in 25863 ms)
03/23 13:18:24 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:24 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3054] GET to https://api.roonlabs.net/discover/1/playlists/roon/editorial?profileId=5dc81ba3-ef1e-4b93-87cf-1efbdab052ce&c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 172 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3061] GET to https://api.tidal.com/v1/featured/recommended/playlists?countryCode=US&offset=0&limit=30 returned after 147 ms, status code: 304, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3060] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=72d5b39f100102f57471fea48a07bf70&request_ts=1742735904&&type=editor-picks returned after 171 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3053] GET to https://api.roonlabs.net/discover/1/mixes/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/mixes?localTime=2025-03-23T09%3a18%3a22.7567620&c=tidal-us,qobuz-us&languages=en,%3Een&tidal=max returned after 196 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3071] GET to https://api.tidal.com/v1/featured/recommended/playlists?countryCode=US&offset=0&limit=30 returned after 103 ms, status code: 304, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3055] GET to https://api.roonlabs.net/internetradio/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack& returned after 232 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3064] GET to https://api.roonlabs.net/discover/1/playlists/roon/editorial?profileId=5dc81ba3-ef1e-4b93-87cf-1efbdab052ce&c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 162 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3056] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/genres?c=tidal-us,qobuz-us&tidal=max returned after 209 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Info: [Broker:Media] [library/albumdetails] found 1 ingroup alternates, 0 other alternates
03/23 13:18:24 Info: [Broker:Media] [library/albumdetails] Created album details screen for LibraryAlbum[7841583, Abdullah Ibrahim - Ancient Africa]
03/23 13:18:24 Info: [Broker:Media] [library/albumdetails] a600313132303134313439, c8006b6834737937346c7371637563, cd002d74413975503544775639554a546161644f
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3070] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=72d5b39f100102f57471fea48a07bf70&request_ts=1742735904&&type=editor-picks returned after 199 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3063] GET to https://api.roonlabs.net/discover/1/mixes/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/mixes?localTime=2025-03-23T09%3a18%3a22.7642000&c=tidal-us,qobuz-us&languages=en,%3Een&tidal=max returned after 222 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3066] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/genres?c=tidal-us,qobuz-us&tidal=max returned after 201 ms, status code: 200, request body size: 0 B
03/23 13:18:24 Debug: [.NET ThreadPool Worker] [easyhttp] [3065] GET to https://api.roonlabs.net/internetradio/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack& returned after 234 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3057] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/performers/recommended?profile=5dc81ba3-ef1e-4b93-87cf-1efbdab052ce&c=tidal-us,qobuz-us&languages=en,%3Een&tidal=max returned after 302 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3069] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/featured/collaboration?c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 263 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3058] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/featured/coveredperformer?c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 403 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3067] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/performers/recommended?profile=5dc81ba3-ef1e-4b93-87cf-1efbdab052ce&c=tidal-us,qobuz-us&languages=en,%3Een&tidal=max returned after 352 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3059] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/featured/collaboration?c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 503 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3074] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=174:1:4324437e-6b25-4268-b69c-2379c2490c52&type=description&sourceLangs=Rovi-albums:en,Rovi-artists:en,Rovi-compositions:en,Wikipedia:en,Wikipedia:fr,Wikipedia:hu&c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 120 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3073] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=166:0:112014149&type=review&sourceLangs=Rovi-albums:en,Rovi-artists:en,Rovi-compositions:en,Wikipedia:en,Wikipedia:fr,Wikipedia:hu&c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 140 ms, status code: 200, request body size: 0 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3072] POST to https://api.roonlabs.net/browse/1/works/trackCounts?c=tidal-us,qobuz-us&tidal=max returned after 152 ms, status code: 200, request body size: 193 B
03/23 13:18:25 Debug: [.NET ThreadPool Worker] [easyhttp] [3068] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/featured/coveredperformer?c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&tidal=max returned after 1119 ms, status code: 200, request body size: 0 B
03/23 13:18:26 Debug: [.NET ThreadPool Worker] [easyhttp] [3052] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/albums/newv3?c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&textSources=Rovi-albums:en,Rovi-artists:en,Rovi-compositions:en,Wikipedia:en,Wikipedia:fr,Wikipedia:hu&tidal=max returned after 2147 ms, status code: 200, request body size: 0 B
03/23 13:18:26 Debug: [.NET ThreadPool Worker] [easyhttp] [3062] GET to https://api.roonlabs.net/discover/1/profiles/5dc81ba3-ef1e-4b93-87cf-1efbdab052ce/albums/newv3?c=tidal-us,qobuz-us&contentPreferences=preferQobuz,avoidMqa&textSources=Rovi-albums:en,Rovi-artists:en,Rovi-compositions:en,Wikipedia:en,Wikipedia:fr,Wikipedia:hu&tidal=max returned after 2201 ms, status code: 200, request body size: 0 B
03/23 13:18:27 Trace: [RaatSender] [LR Yggy AES] [HighQuality, 16/44 QOBUZ FLAC => 16/44] [100% buf] [PLAYING @ 6:19/19:29] Ancient Africa - Abdullah Ibrahim
03/23 13:18:27 Trace: [Broker:Misc] [remoting/brokerserver] [initconn 192.168.3.92:52964=>192.168.1.101:9332] Connected
03/23 13:18:27 Trace: [Broker:Misc] [remoting/brokerserver] [initconn 192.168.3.92:52964=>192.168.1.101:9332] Initialized Session because we couldn't resume
03/23 13:18:27 Trace: [Broker:Misc] [remoting/brokerserver] killing old client from 468a501d-93db-4ccf-a2e1-a38f3f2241e7 because we have a new one
03/23 13:18:27 Info: [Broker:Misc] [remoting/serverconnectionv2] Client connected: 192.168.3.92:52964
03/23 13:18:27 Trace: [Broker:Transport] [raat] [sood] Refreshing device list
03/23 13:18:27 Trace: [Broker:Misc] [remoting/brokerserver] [initconn 192.168.6.250:54267=>192.168.1.101:9332] Connected
03/23 13:18:27 Trace: [Broker:Transport] [raatserver] [sood] Refreshing device list
03/23 13:18:27 Trace: [Broker:Misc] [remoting/brokerserver] [initconn 192.168.6.250:54267=>192.168.1.101:9332] Initialized Session because we couldn't resume
03/23 13:18:27 Trace: [Broker:Misc] [remoting/brokerserver] killing old client from 468a501d-93db-4ccf-a2e1-a38f3f2241e7 because we have a new one
03/23 13:18:27 Info: [Broker:Misc] [remoting/serverconnectionv2] Client connected: 192.168.6.250:54267
03/23 13:18:27 Trace: [Broker:Transport] [raat] [sood] Refreshing device list
03/23 13:18:27 Trace: [Broker:Transport] [raatserver] [sood] Refreshing device list
03/23 13:18:27 Trace: [RemotingServerV2] [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=0ef8087b-5cc7-4ea7-9ca1-7b400c2c93a7 BrokerName='Brookline ROCK' }
03/23 13:18:27 Trace: [RemotingServerV2] [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=204801515 DisplayValue='2.48 (build 1515) earlyaccess' Branch='earlyaccess' } }
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
03/23 13:18:27 Trace: [Broker:Media] [radio/query] query returned 3 Sooloos.Broker.Api.Channel(s)
03/23 13:18:27 Trace: [Broker:Media] [radio/query] performing channel query
And then, out of nowhere, it begins to behave again just fine, and is fine for 15 or 20 minutes, and then this begins again. I have no idea what the trigger is for it doing ok or not. I have been rebooting etc.
I guess the one other edge case-y thing about my setup is that I have one license, and two cores (in different houses) and I do go back and forth between them, so I unauthorize one home’s core and authorize the other’s a few times a month. I have also played around with udp-proxy-2020 trying to get a single core working across two homes, but have never succeeded fully. Even if that worked, that would mean that it looked like I had two cores on the same local network and all endpoints were local because multicasting was working across vLANs.
For what it’s worth, I’d be happy to completely disable live radio and make it either non-functional or non-available from the interface if it were possible and would help. I know that’s a feature request. But given I’ve ended up in this spot a few times, and I’m guessing others have, it’d be a fine solution (for me).
Here’s the previous thread to merge with:
And another troubleshooting thing to play with, which makes me think that there’s something strange on the Roon server end for me. I tried to set up a core on my Mac mini M4 desktop, just to see what would happen if I restored a backup. I turned off Roon Server Software on my ROCK from the webadmin page, and then started the Roon app on my Mac. It didn’t find the core, so I selected “Use this Mac as a core”. I said no to adding my music, and no to adding qobuz / tidal because I wanted to restore the backup first. But I can’t get to the settings page, because it keeps repainting the page over and over again. There’s no history on this core - I’ve never set up a core on this Mac before, just used it as a controller. But it’s stuck in some sort of loop where it’s reloading the Home Screen once every ~second. I looked at logs on the Mac and found this - not sure if related or not.
03/23 10:25:19 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] Authenticating => Connected
03/23 10:25:19 Debug: [1] broker and local broker share machine id, updates for client disabled
03/23 10:25:19 Info: [1] [client/root] Broker changed JohnsMiniM4197 (Remote Broker eac6d83e-8cd8-451e-a2b0-44d3d8206efd) => JohnsMiniM4197 (Remote Broker eac6d83e-8cd8-451e-a2b0-44d3d8206efd)
03/23 10:25:19 Info: [1] [client/root] Client is acting as a remote
03/23 10:25:19 Info: [1] [client/root] Broker ready changed False => True
03/23 10:25:19 Trace: [1] [bits] myinfo: {"pushid":"broker/468a501d-93db-4ccf-a2e1-a38f3f2241e7","roon_auth_token":"dc846648-584e-4621-a868-2e852fb26ea7","os":"Mac OS X 15.3.2","platform":"macosx","machineversion":204801515,"branch":"earlyaccess","appmodifier":"","appname":"Roon"}
03/23 10:25:19 Info: [1] [ui] loading home screen
03/23 10:25:19 Debug: [1] trigger: appinitwasrun
03/23 10:25:19 Debug: [1] trigger: apploaded, restoring nav stack
03/23 10:25:19 Debug: [1] GMS: restoring nav stack
03/23 10:25:19 Debug: [1] GMS: restoring nav stack data: trackbrowser
03/23 10:25:19 Debug: [1] GMS: restoring nav stack data: albumbrowser
03/23 10:25:19 Trace: [1] DisposeReusableCellCache: scrollpanel(3882), 0 disposed from cache.
03/23 10:25:19 Warn: [1] AddTopLevel: win_main(261)
03/23 10:25:19 Trace: [1] DisposeReusableCellCache: scrollpanel(3882), 0 disposed from cache.
03/23 10:25:19 Info: [1] [ui] running segmentation question
03/23 10:25:19 Debug: [1] after delayed_start_work
03/23 10:25:19 Trace: [1] DisposeReusableCellCache: scrollpanel(3882), 3 disposed from cache.
03/23 10:25:19 Debug: [.NET ThreadPool Worker] [easyhttp] [64] POST to https://api.roonlabs.net/bits/1/q/roon.base.,roon.internet_discovery.,roon.debug.,roon.client.,roon.broker.,roon.sood.?roon_auth_token=dc846648-584e-4621-a868-2e852fb26ea7 returned after 187 ms, status code: 200, request body size: 240 B
03/23 10:25:19 Trace: [1] [bits] updated bits, in 199ms
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: artistbrowser
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: composerbrowser
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: workbrowser
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: tagbrowser
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: playlistbrowser
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: playlistdetails
03/23 10:25:20 Info: [1] [ui] playlistdetails: blobversion=2
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: folderbrowsertoplevel
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: folderdetaildesktop
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: folderdetailphone
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: nowplaying
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: sidebar
03/23 10:25:20 Debug: [1] GMS: restoring nav stack data: screens
03/23 10:25:20 Debug: [1] UI-FWD: skipping fwd2 due to lazyload: home
03/23 10:25:20 Debug: [1] UI-FWD: skipping fwd2 due to lazyload: albumbrowser
03/23 10:25:20 Debug: [1] GMS: found currentscreen in GMS file, going to index 1
03/23 10:25:20 Debug: [1] UI-FORCE-UNLAZY: mode: albumbrowser
03/23 10:25:20 Debug: [1] GMS: trying to save nav stack, but nav stack stuff was in progress
03/23 10:25:20 Debug: [1] UI-FWD: mode: albumbrowser
03/23 10:25:20 Debug: [1] GMS: trying to save nav stack, but nav stack stuff was in progress
03/23 10:25:20 Debug: [1] UI-NAV: album browser / focus: / infobar: / bookmarkdata: [object Broo.Runtime.BrooMap]
03/23 10:25:20 Debug: [1] GMS: done restoring nav stack
03/23 10:25:20 Trace: [1] DisposeReusableCellCache: scrollpanel(3882), 0 disposed from cache.
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] Connection dropped: Id: eac6d83e-8cd8-451e-a2b0-44d3d8206efd Name: JohnsMiniM4197: 127.0.0.1 tcp=9331 tcpv2=9332, http=9330, inet=False, timestamp=3/23/2025 2:21:10PM
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] disconnect(hard=False)
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] Connected => Connecting
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] initializing with InitConnection[JohnsMiniM4197@127.0.0.1:9332, state=Idle]
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] Connecting => ConnectionFailed
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] ConnectionFailed => Authenticating
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotingclientv2] SENT REQUEST DistributedBroker.ConnectRequest={ ClientBrokerId=468a501d-93db-4ccf-a2e1-a38f3f2241e7 ClientBrokerName='JohnsMiniM4197' ProtocolVersion='28' ProtocolHash='969cf855880c4a96520c5394039d79d4a037cb4f' ClientBranch='earlyaccess' }
03/23 10:25:20 Trace: [Broker:Remoting] [remoting/remotingclientv2] GOT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=eac6d83e-8cd8-451e-a2b0-44d3d8206efd BrokerName='JohnsMiniM4197' }
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] connected to JohnsMiniM4197 (eac6d83e-8cd8-451e-a2b0-44d3d8206efd)
03/23 10:25:20 Trace: [Broker:Remoting] [remoting/remotingclientv2] GOT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=204801515 DisplayValue='2.48 (build 1515) earlyaccess' Branch='earlyaccess' } }
03/23 10:25:20 Trace: [Broker:Misc] [remoting/remotebrokerv2] [JohnsMiniM4197] Authenticating => Connected
03/23 10:25:20 Debug: [1] broker and local broker share machine id, updates for client disabled
03/23 10:25:20 Info: [1] [client/root] Broker changed JohnsMiniM4197 (Remote Broker eac6d83e-8cd8-451e-a2b0-44d3d8206efd) => JohnsMiniM4197 (Remote Broker eac6d83e-8cd8-451e-a2b0-44d3d8206efd)
03/23 10:25:20 Info: [1] [client/root] Client is acting as a remote
03/23 10:25:20 Info: [1] [client/root] Broker ready changed False => True
03/23 10:25:20 Trace: [1] [bits] myinfo: {"pushid":"broker/468a501d-93db-4ccf-a2e1-a38f3f2241e7","roon_auth_token":"dc846648-584e-4621-a868-2e852fb26ea7","os":"Mac OS X 15.3.2","platform":"macosx","machineversion":204801515,"branch":"earlyaccess","appmodifier":"","appname":"Roon"}
03/23 10:25:20 Info: [1] [ui] loading home screen
Never really thought I’d say this, but in the meantime I’m going to add my library to HQPlayer Desktop and listen directly through the library management features there, because Roon is basically unusable right now.
Ok, one more thing… It must be platform independent and somewhere in the state I got into. I created a brand new core on the Mac, reloaded my backup, and within a few minutes it was in the same exact paint/repaint loop, and I looked in the Mac logs and lo and behold:
Ok, I’ve done this a few times. Whatever version I restore from seems to eventually get into this crazy endless loop of repainting the screen, and sometimes calming down, and then within 20 minutes to an hour going back into completely spastic mode. I can check logs, but the problem in trying to figure out what’s triggering the path into or out of the bad state is that the logs begin to fill up so darn fast once they’re in a bad state so you kind of have to catch the logs in real time, but I don’t know what I’m looking for. Now I have both the ROCK core (192.168.1.101) and the Mac core (192.168.1.5) which are basically in the same exact behavior loop. And I’m kind of tired of it so I am playing out of HQPDesktop directly.
We’ll be best able to reproduce this with a copy of the DB itself.
Are you able to upload a copy of the specific Backup/database that causes this behavior predictably? Please zip the entire RoonServer file and upload here at your convenience with that Backup restored: Database Uploader
Are there any screens within the GUI that load properly?
Excellent. Well, right now I have a “virgin” install going (blew away all data, re-added library and audio zones), and it’s working fine. I changed some things in my folder structure which might have triggered this behavior, so I’m going to wait for a restore (which is 800GB over cable modems between my homes bottlenecked by my 12Mb/sec cap on egress from my second home and hence will take close to a week), and when I do a restore, I’m going to restore with my library exactly as it was prior to the directory re-org. At that point I’ll upload the RoonServer folder. It doesn’t seem fair to you guys to troubleshoot something that I may have brought on myself and think I can undo.
But… do you mind leaving this ticket open while I wait for my restore? I hope your SLAs don’t get hit if we say “let’s hit pause for a week”. If that doesn’t work for you, we can re-open this again when I’m ready. Or I can restore the problematic state and upload it if it’s interesting for you.
Hi @Johnny_Ooooops,
I’ve set this thread to stay open for another two weeks. If you need more time, just drop another comment, and the timer will reset automatically. We’ll be keeping an eye out for your response once you have everything set up.