"Instant Search" times out or is very slow

Karsten_Feilke · January 6, 2023, 7:06pm

Roon Core Machine

Roon 2.0, build 1182
Linux based server / OSX remote
Intel Core I5/8400 (CPU Utilization <5%),
16GB RAM (9GB Free),
Raid 5 with M.2 Cache @ >300MB/s, large audio DB

Networking Gear & Setup Details

Gigabit Ethernet >Cable> Unmanaged Cisco Gigabit Switch >Cable> Fritzbox
No problems with Network / Internet Connectivity, Roon is able to fetch meta data / Pictures e.g.
IPV4 only, fixed DNS to 8.8.8.8 / 1.1.1.1 already

Roon Core audio streaming and library navigation is in general super fast, streaming starts almost always instantaneously (in a fraction of a second for all my endpoints and remotes), applying filters to the album or artist view happens instantaneously as well (frac of a sec) but when using the “search box” (instant search, top right) queries take ages (at least 4-5s) or time out after 8s.

Read a few other articles about search related problems and disabled my Qobuz account already (did not make a difference) and restarted the core afterwards (did also not improve the search performance). Restarted the server, the core & remote (no effect).

Example queries: Daft Punk takes approximately 5s or fails

01/06 19:42:09 Debug: [easyhttp] [657] POST to https://search.roonlabs.net/1/instantsearch2?q=da&count=8 canceled after 7801 ms
01/06 19:42:09 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
   at Base.Result`1.get_Value()
   at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
   at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()
01/06 19:42:09 Debug: [easyhttp] [658] POST to https://search.roonlabs.net/1/instantsearch2?q=daf&count=8 canceled after 7989 ms
01/06 19:42:09 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
   at Base.Result`1.get_Value()
   at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
   at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()
01/06 19:42:09 Debug: [easyhttp] [660] POST to https://search.roonlabs.net/1/instantsearch2?q=daft%20p&count=8 returned after 7205 ms, status code: 200
01/06 19:42:09 Debug: [easyhttp] [659] POST to https://search.roonlabs.net/1/instantsearch2?q=daft&count=8 canceled after 7995 ms
01/06 19:42:09 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
   at Base.Result`1.get_Value()
   at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
   at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()
01/06 19:42:10 Debug: [easyhttp] [661] POST to https://search.roonlabs.net/1/instantsearch2?q=daft%20pu&count=8 canceled after 7987 ms
01/06 19:42:10 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
   at Base.Result`1.get_Value()
   at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
   at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()
01/06 19:42:10 Debug: [easyhttp] [662] POST to https://search.roonlabs.net/1/instantsearch2?q=daft%20pun&count=8 canceled after 7994 ms
01/06 19:42:10 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
   at Base.Result`1.get_Value()
   at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
   at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()
01/06 19:42:10 Debug: [easyhttp] [663] POST to https://search.roonlabs.net/1/instantsearch2?q=daft%20punk&count=8 canceled after 7994 ms
01/06 19:42:10 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
   at Base.Result`1.get_Value()
   at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
   at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()

Beatles takes approximately 5s
1/06 19:41:57 Debug: [easyhttp] [656] POST to https://search.roonlabs.net/1/instantsearch2?q=beatles&count=8 returned after 4581 ms, status code: 200

“James Arthur” is another example I tried a couple of times. Interestingly these do always fail and the roon remote UI renders a “Cannot establish a connection to Roon Search” error message. Logs follow the pattern I provided for the “Daft Punk” query (canceled after 8s).
When going to “Albums”, filtering to “James Arthur” Roon immediately returns an album and streaming to an end point also starts without any latency.

I am not facing any issues when opening up albums (Roon meta data is rendered) or importing new albums into my library (fetching metadate typically takes seconds). The problem only occurs when using the search box.

Not sure why Roon sends an online query. Would be fine for me to have an option to make this a local query (artists, albums, tracks) but 5-8s or having these timeouts is super annoying.

Karsten_Feilke · January 6, 2023, 7:13pm

More tests - as already said “James Arthur” always fails for me, “Beatles” typically works but takes approximately 5s, “Alexa Feser” or “Frida Gold” are subjective faster (2-4s) but do also fail sometimes.
Even when typing quickly Roon sends a couple of queries to the remote server. Response time varies
quite a lot even for the queries that are send at the same time:

01/06 20:08:52 Debug: [easyhttp] [753] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20feser&count=8 returned after 2606 ms, status code: 200
01/06 20:08:52 Debug: [easyhttp] [752] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20fes&count=8 returned after 3088 ms, status code: 200
01/06 20:08:52 Debug: [easyhttp] [750] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20f&count=8 returned after 3802 ms, status code: 200
01/06 20:08:55 Debug: [easyhttp] [747] POST to https://search.roonlabs.net/1/instantsearch2?q=ale&count=8 canceled after 7915 ms
01/06 20:08:56 Debug: [easyhttp] [748] POST to https://search.roonlabs.net/1/instantsearch2?q=alex&count=8 canceled after 7922 ms
01/06 20:08:56 Debug: [easyhttp] [749] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa&count=8 canceled after 7946 ms
01/06 20:08:56 Debug: [easyhttp] [751] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20fe&count=8 canceled after 7954 ms
01/06 20:09:01 Debug: [easyhttp] [760] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20feser&count=8 returned after 1836 ms, status code: 200
01/06 20:09:03 Debug: [easyhttp] [759] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20fes&count=8 returned after 3829 ms, status code: 200
01/06 20:09:04 Debug: [easyhttp] [757] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20f&count=8 returned after 5131 ms, status code: 200
01/06 20:09:06 Debug: [easyhttp] [754] POST to https://search.roonlabs.net/1/instantsearch2?q=ale&count=8 canceled after 7917 ms
01/06 20:09:06 Debug: [easyhttp] [755] POST to https://search.roonlabs.net/1/instantsearch2?q=alex&count=8 canceled after 7918 ms
01/06 20:09:06 Debug: [easyhttp] [756] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa&count=8 canceled after 7941 ms
01/06 20:09:07 Debug: [easyhttp] [758] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20fe&count=8 canceled after 7955 ms
01/06 20:09:13 Debug: [easyhttp] [765] POST to https://search.roonlabs.net/1/instantsearch2?q=frida%20gold&count=8 returned after 1274 ms, status code: 200
01/06 20:09:15 Debug: [easyhttp] [764] POST to https://search.roonlabs.net/1/instantsearch2?q=frida%20go&count=8 returned after 3578 ms, status code: 200
01/06 20:09:19 Debug: [easyhttp] [761] POST to https://search.roonlabs.net/1/instantsearch2?q=frid&count=8 canceled after 7966 ms
01/06 20:09:19 Debug: [easyhttp] [762] POST to https://search.roonlabs.net/1/instantsearch2?q=frida&count=8 canceled after 7990 ms
01/06 20:09:19 Debug: [easyhttp] [763] POST to https://search.roonlabs.net/1/instantsearch2?q=frida&count=8 canceled after 7995 ms
01/06 20:09:20 Debug: [easyhttp] [776] POST to https://search.roonlabs.net/1/instantsearch2?q=frida%20gold&count=8 returned after 2415 ms, status code: 200
01/06 20:09:23 Debug: [easyhttp] [775] POST to https://search.roonlabs.net/1/instantsearch2?q=frida%20go&count=8 returned after 5144 ms, status code: 200
01/06 20:09:24 Debug: [easyhttp] [770] POST to https://search.roonlabs.net/1/instantsearch2?q=fi&count=8 canceled after 7901 ms
01/06 20:09:24 Debug: [easyhttp] [771] POST to https://search.roonlabs.net/1/instantsearch2?q=fr&count=8 canceled after 7821 ms
01/06 20:09:25 Debug: [easyhttp] [772] POST to https://search.roonlabs.net/1/instantsearch2?q=frid&count=8 canceled after 7963 ms
01/06 20:09:25 Debug: [easyhttp] [773] POST to https://search.roonlabs.net/1/instantsearch2?q=frida&count=8 canceled after 7990 ms
01/06 20:09:25 Debug: [easyhttp] [774] POST to https://search.roonlabs.net/1/instantsearch2?q=frida&count=8 canceled after 7996 ms
01/06 20:09:28 Debug: [easyhttp] [779] POST to https://search.roonlabs.net/1/instantsearch2?q=beatle&count=8 returned after 5365 ms, status code: 200
01/06 20:09:29 Debug: [easyhttp] [780] POST to https://search.roonlabs.net/1/instantsearch2?q=beatles&count=8 returned after 6644 ms, status code: 200
01/06 20:09:30 Debug: [easyhttp] [777] POST to https://search.roonlabs.net/1/instantsearch2?q=be&count=8 canceled after 7753 ms
01/06 20:09:30 Debug: [easyhttp] [778] POST to https://search.roonlabs.net/1/instantsearch2?q=bea&count=8 canceled after 7929 ms
01/06 20:09:35 Debug: [easyhttp] [785] POST to https://search.roonlabs.net/1/instantsearch2?q=frida%20gold&count=8 returned after 2688 ms, status code: 200
01/06 20:09:37 Debug: [easyhttp] [784] POST to https://search.roonlabs.net/1/instantsearch2?q=frida%20go&count=8 returned after 5150 ms, status code: 200
01/06 20:09:39 Debug: [easyhttp] [781] POST to https://search.roonlabs.net/1/instantsearch2?q=fr&count=8 canceled after 7727 ms
01/06 20:09:39 Debug: [easyhttp] [782] POST to https://search.roonlabs.net/1/instantsearch2?q=frid&count=8 canceled after 7964 ms
01/06 20:09:39 Debug: [easyhttp] [783] POST to https://search.roonlabs.net/1/instantsearch2?q=frida&count=8 canceled after 7990 ms
01/06 20:09:43 Debug: [easyhttp] [792] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20feser&count=8 returned after 5063 ms, status code: 200
01/06 20:09:45 Debug: [easyhttp] [791] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20fes&count=8 returned after 6586 ms, status code: 200
01/06 20:09:45 Debug: [easyhttp] [786] POST to https://search.roonlabs.net/1/instantsearch2?q=ale&count=8 canceled after 7924 ms
01/06 20:09:45 Debug: [easyhttp] [789] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20f&count=8 returned after 7424 ms, status code: 200
01/06 20:09:45 Debug: [easyhttp] [787] POST to https://search.roonlabs.net/1/instantsearch2?q=alex&count=8 canceled after 7931 ms
01/06 20:09:45 Debug: [easyhttp] [788] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa&count=8 canceled after 7946 ms
01/06 20:09:46 Debug: [easyhttp] [790] POST to https://search.roonlabs.net/1/instantsearch2?q=alexa%20fe&count=8 canceled after 7953 ms
01/06 20:09:57 Debug: [easyhttp] [793] POST to https://search.roonlabs.net/1/instantsearch2?q=ja&count=8 canceled after 7829 ms
01/06 20:09:57 Debug: [easyhttp] [794] POST to https://search.roonlabs.net/1/instantsearch2?q=jame&count=8 canceled after 7911 ms
01/06 20:09:57 Debug: [easyhttp] [795] POST to https://search.roonlabs.net/1/instantsearch2?q=james&count=8 canceled after 7923 ms
01/06 20:09:57 Debug: [easyhttp] [796] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20a&count=8 canceled after 7923 ms
01/06 20:09:58 Debug: [easyhttp] [797] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arth&count=8 canceled after 7970 ms
01/06 20:09:58 Debug: [easyhttp] [798] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arthur&count=8 canceled after 7977 ms

Karsten_Feilke · January 6, 2023, 7:22pm

There is only this error pattern in the log:

01/06 20:09:58 Debug: [easyhttp] [797] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arth&count=8 canceled after 7970 ms
  01/06 20:09:58 Critical: [search] error performing instant search: System.InvalidOperationException: Operation is not valid due to the current state of the object.
     at Base.Result`1.get_Value()
     at Sooloos.Broker.Music.LibraryApi.RunInstantSearch(SearchParameters searchParameters, CancellationToken cancellationToken)
     at Sooloos.Broker.Music.LibraryApi.<>c__DisplayClass5_0.<<Sooloos-Broker-Api-Library-UnifiedAutocomplete>b__0>d.MoveNext()

No other lines flagged as critical / error:

cat RoonServer_log.txt | grep -v Info | grep -v “Critical: [search]” | grep Critical

no results

cat RoonServer_log.txt | grep -v Info | grep -v “Critical: [search]” | grep Error

no results

noris · January 10, 2023, 6:27pm

Hi @Karsten_Feilke ,

Thanks for reaching out.

Can you please try to temporarily host the Roon Core on your OSX remote and verify if you are seeing the same issue from there? Or is it only impacting the Linux Core? To switch Cores:

Create a Backup of your current database
Open Roon on the other PC you wish to try as the Core
Roon Settings → General
Disconnect
On the “Choose your Core” screen, press “Use this PC”
If asked to Unauthorize, you can go ahead and do so. You are limited to one active Roon Core at a time but you are free to switch between them as often as you’d like.
Verify if the same behavior occurs on the different PC

Karsten_Feilke · January 10, 2023, 8:59pm

Hi Noris,

appreciate your support. Temporary made OSX my Roon Core (from scratch), copied over one single album and sent the James Arthur query again. And it works and comes back quickly.

01/10 21:33:12 Debug: [easyhttp] [50] POST to https://search.roonlabs.net/1/instantsearch2?q=ja&count=8 returned after 496 ms, status code: 200
01/10 21:33:12 Debug: [easyhttp] [51] POST to https://search.roonlabs.net/1/instantsearch2?q=jame&count=8 returned after 477 ms, status code: 200
01/10 21:33:12 Debug: [easyhttp] [52] POST to https://search.roonlabs.net/1/instantsearch2?q=james&count=8 returned after 479 ms, status code: 200
01/10 21:33:12 Debug: [easyhttp] [53] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20a&count=8 returned after 489 ms, status code: 200
01/10 21:33:13 Debug: [easyhttp] [54] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20ar&count=8 returned after 480 ms, status code: 200
01/10 21:33:13 Debug: [easyhttp] [55] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arth&count=8 returned after 484 ms, status code: 200
01/10 21:33:13 Debug: [easyhttp] [56] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arthur&count=8 returned after 482 ms, status code: 200

Then I importet the DB backup from my Linux Core (a 7GB backup) and I also corrected the music folder (mounted the linux folder as a SMB share on OSX). Once done the OSX Roon core started to list and scan all my albums. Playback works without any issues & latency but the search problem persists:

01/10 21:35:09 Debug: [easyhttp] [156] POST to https://search.roonlabs.net/1/instantsearch2?q=ja&count=8 canceled after 7425 ms
01/10 21:35:09 Debug: [easyhttp] [157] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arthur&count=8 returned after 7601 ms, status code: 200
01/10 21:35:58 Debug: [easyhttp] [1028] POST to https://search.roonlabs.net/1/instantsearch2?q=beatle&count=8 returned after 3801 ms, status code: 200
01/10 21:36:00 Debug: [easyhttp] [1019] POST to https://search.roonlabs.net/1/instantsearch2?q=be&count=8 canceled after 6682 ms
01/10 21:36:01 Debug: [easyhttp] [1020] POST to https://search.roonlabs.net/1/instantsearch2?q=bea&count=8 canceled after 7870 ms
01/10 21:36:01 Debug: [easyhttp] [1021] POST to https://search.roonlabs.net/1/instantsearch2?q=beal&count=8 canceled after 7993 ms
01/10 21:36:01 Debug: [easyhttp] [1022] POST to https://search.roonlabs.net/1/instantsearch2?q=bea&count=8 canceled after 7866 ms
01/10 21:36:02 Debug: [easyhttp] [1023] POST to https://search.roonlabs.net/1/instantsearch2?q=beatl&count=8 canceled after 7990 ms
01/10 21:36:02 Debug: [easyhttp] [1036] POST to https://search.roonlabs.net/1/instantsearch2?q=beatles&count=8 canceled after 8001 ms
01/10 21:36:14 Debug: [easyhttp] [1228] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arh&count=8 returned after 6222 ms, status code: 200
01/10 21:36:14 Debug: [easyhttp] [1219] POST to https://search.roonlabs.net/1/instantsearch2?q=ja&count=8 canceled after 7412 ms
01/10 21:36:15 Debug: [easyhttp] [1220] POST to https://search.roonlabs.net/1/instantsearch2?q=jam&count=8 canceled after 7813 ms
01/10 21:36:15 Debug: [easyhttp] [1221] POST to https://search.roonlabs.net/1/instantsearch2?q=jame&count=8 canceled after 7857 ms
01/10 21:36:15 Debug: [easyhttp] [1222] POST to https://search.roonlabs.net/1/instantsearch2?q=james&count=8 canceled after 7872 ms
01/10 21:36:15 Debug: [easyhttp] [1223] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20a&count=8 canceled after 7910 ms
01/10 21:36:16 Debug: [easyhttp] [1226] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20ar&count=8 canceled after 7810 ms
01/10 21:36:18 Debug: [easyhttp] [1248] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arthur&count=8 canceled after 8386 ms
01/10 21:36:18 Debug: [easyhttp] [1236] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20art&count=8 canceled after 8909 ms
01/10 21:36:18 Debug: [easyhttp] [1241] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arth&count=8 canceled after 8700 ms

Interestingly the “James Arthur” query did not always fail but most queries are still slow (multiple seconds) or fail. OSX Core runs on a M1 Max MacBook Pro and everything except for the query is super fast.

Karsten_Feilke · January 10, 2023, 9:23pm

Now back on the Linux Core: most queries were successful a few minutes ago, most came back after 1-3s (ok but still feels quite slow) and ‘James Arthur’ still tends to fail almost always.

01/10 22:14:59 Debug: [easyhttp] [1569] POST to https://search.roonlabs.net/1/instantsearch2?q=jame&count=8&c=qobuz-de canceled after 7917 ms
01/10 22:15:00 Debug: [easyhttp] [1570] POST to https://search.roonlabs.net/1/instantsearch2?q=james&count=8&c=qobuz-de canceled after 7924 ms
01/10 22:15:00 Debug: [easyhttp] [1571] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20a&count=8&c=qobuz-de canceled after 7923 ms
01/10 22:15:01 Debug: [easyhttp] [1572] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20ar&count=8&c=qobuz-de canceled after 7822 ms
01/10 22:15:01 Debug: [easyhttp] [1573] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arth&count=8&c=qobuz-de canceled after 7970 ms
01/10 22:15:01 Debug: [easyhttp] [1574] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arthur&count=8&c=qobuz-de canceled after 7977 ms
01/10 22:15:09 Debug: [easyhttp] [1576] POST to https://search.roonlabs.net/1/instantsearch2?q=2ce&count=8&c=qobuz-de returned after 2856 ms, status code: 200
01/10 22:15:09 Debug: [easyhttp] [1577] POST to https://search.roonlabs.net/1/instantsearch2?q=2cel&count=8&c=qobuz-de returned after 2676 ms, status code: 200
01/10 22:15:09 Debug: [easyhttp] [1578] POST to https://search.roonlabs.net/1/instantsearch2?q=2cell&count=8&c=qobuz-de returned after 2631 ms, status code: 200
01/10 22:15:09 Debug: [easyhttp] [1575] POST to https://search.roonlabs.net/1/instantsearch2?q=2c&count=8&c=qobuz-de returned after 3347 ms, status code: 200
01/10 22:15:10 Debug: [easyhttp] [1579] POST to https://search.roonlabs.net/1/instantsearch2?q=2cellos&count=8&c=qobuz-de returned after 2737 ms, status code: 200
01/10 22:15:21 Debug: [easyhttp] [1592] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20doo&count=8&c=qobuz-de returned after 1329 ms, status code: 200
01/10 22:15:21 Debug: [easyhttp] [1593] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20door&count=8&c=qobuz-de returned after 1217 ms, status code: 200
01/10 22:15:21 Debug: [easyhttp] [1594] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20doors&count=8&c=qobuz-de returned after 1142 ms, status code: 200
01/10 22:15:21 Debug: [easyhttp] [1595] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20doors&count=8&c=qobuz-de returned after 1232 ms, status code: 200
01/10 22:15:22 Debug: [easyhttp] [1590] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20d&count=8&c=qobuz-de returned after 3469 ms, status code: 200
01/10 22:15:23 Debug: [easyhttp] [1596] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20doors%20down&count=8&c=qobuz-de returned after 1992 ms, status code: 200
01/10 22:15:24 Debug: [easyhttp] [1591] POST to https://search.roonlabs.net/1/instantsearch2?q=3%20do&count=8&c=qobuz-de returned after 4569 ms, status code: 200
01/10 22:15:29 Debug: [easyhttp] [1601] POST to https://search.roonlabs.net/1/instantsearch2?q=50%20c&count=8&c=qobuz-de returned after 1502 ms, status code: 200
01/10 22:15:31 Debug: [easyhttp] [1602] POST to https://search.roonlabs.net/1/instantsearch2?q=50%20cen&count=8&c=qobuz-de returned after 2500 ms, status code: 200
01/10 22:15:31 Debug: [easyhttp] [1603] POST to https://search.roonlabs.net/1/instantsearch2?q=50%20cent&count=8&c=qobuz-de returned after 2819 ms, status code: 200
01/10 22:15:31 Debug: [easyhttp] [1600] POST to https://search.roonlabs.net/1/instantsearch2?q=50&count=8&c=qobuz-de returned after 3910 ms, status code: 200
01/10 22:15:42 Debug: [easyhttp] [1649] POST to https://search.roonlabs.net/1/instantsearch2?q=aal&count=8&c=qobuz-de returned after 1516 ms, status code: 200
01/10 22:15:42 Debug: [easyhttp] [1650] POST to https://search.roonlabs.net/1/instantsearch2?q=aali&count=8&c=qobuz-de returned after 1452 ms, status code: 200
01/10 22:15:42 Debug: [easyhttp] [1651] POST to https://search.roonlabs.net/1/instantsearch2?q=aaliy&count=8&c=qobuz-de returned after 1232 ms, status code: 200
01/10 22:15:42 Debug: [easyhttp] [1652] POST to https://search.roonlabs.net/1/instantsearch2?q=aaliya&count=8&c=qobuz-de returned after 943 ms, status code: 200
01/10 22:15:43 Debug: [easyhttp] [1653] POST to https://search.roonlabs.net/1/instantsearch2?q=aaliyah&count=8&c=qobuz-de returned after 972 ms, status code: 200
01/10 22:15:46 Debug: [easyhttp] [1648] POST to https://search.roonlabs.net/1/instantsearch2?q=aa&count=8&c=qobuz-de returned after 6284 ms, status code: 200
01/10 22:15:52 Debug: [easyhttp] [1659] POST to https://search.roonlabs.net/1/instantsearch2?q=abba&count=8&c=qobuz-de returned after 2867 ms, status code: 200
01/10 22:15:57 Debug: [easyhttp] [1658] POST to https://search.roonlabs.net/1/instantsearch2?q=ab&count=8&c=qobuz-de canceled after 7941 ms
01/10 22:16:03 Debug: [easyhttp] [1688] POST to https://search.roonlabs.net/1/instantsearch2?q=adele&count=8&c=qobuz-de returned after 2706 ms, status code: 200
01/10 22:16:08 Debug: [easyhttp] [1687] POST to https://search.roonlabs.net/1/instantsearch2?q=ad&count=8&c=qobuz-de canceled after 7927 ms
01/10 22:16:13 Debug: [easyhttp] [1690] POST to https://search.roonlabs.net/1/instantsearch2?q=ae&count=8&c=qobuz-de returned after 1511 ms, status code: 200
01/10 22:16:14 Debug: [easyhttp] [1691] POST to https://search.roonlabs.net/1/instantsearch2?q=aer&count=8&c=qobuz-de returned after 1443 ms, status code: 200
01/10 22:16:14 Debug: [easyhttp] [1693] POST to https://search.roonlabs.net/1/instantsearch2?q=aeros&count=8&c=qobuz-de returned after 1256 ms, status code: 200
01/10 22:16:15 Debug: [easyhttp] [1692] POST to https://search.roonlabs.net/1/instantsearch2?q=aero&count=8&c=qobuz-de returned after 1627 ms, status code: 200
01/10 22:16:15 Debug: [easyhttp] [1694] POST to https://search.roonlabs.net/1/instantsearch2?q=aerosm&count=8&c=qobuz-de returned after 1307 ms, status code: 200
01/10 22:16:15 Debug: [easyhttp] [1695] POST to https://search.roonlabs.net/1/instantsearch2?q=aerosmith&count=8&c=qobuz-de returned after 1120 ms, status code: 200
01/10 22:16:26 Debug: [easyhttp] [1699] POST to https://search.roonlabs.net/1/instantsearch2?q=ja&count=8&c=qobuz-de canceled after 7848 ms
01/10 22:16:26 Debug: [easyhttp] [1700] POST to https://search.roonlabs.net/1/instantsearch2?q=jame&count=8&c=qobuz-de canceled after 7919 ms
01/10 22:16:26 Debug: [easyhttp] [1701] POST to https://search.roonlabs.net/1/instantsearch2?q=james&count=8&c=qobuz-de canceled after 7920 ms
01/10 22:16:26 Debug: [easyhttp] [1702] POST to https://search.roonlabs.net/1/instantsearch2?q=james&count=8&c=qobuz-de canceled after 7925 ms
01/10 22:16:27 Debug: [easyhttp] [1703] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20a&count=8&c=qobuz-de canceled after 7923 ms
01/10 22:16:27 Debug: [easyhttp] [1704] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20ar&count=8&c=qobuz-de canceled after 7859 ms
01/10 22:16:27 Debug: [easyhttp] [1705] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arth&count=8&c=qobuz-de canceled after 7971 ms
01/10 22:16:28 Debug: [easyhttp] [1706] POST to https://search.roonlabs.net/1/instantsearch2?q=james%20arthur&count=8&c=qobuz-de canceled after 7978 ms
01/10 22:16:33 Debug: [easyhttp] [1711] POST to https://search.roonlabs.net/1/instantsearch2?q=agnes%20o&count=8&c=qobuz-de returned after 1069 ms, status code: 200
01/10 22:16:34 Debug: [easyhttp] [1712] POST to https://search.roonlabs.net/1/instantsearch2?q=agnes%20obe&count=8&c=qobuz-de returned after 1489 ms, status code: 200
01/10 22:16:34 Debug: [easyhttp] [1713] POST to https://search.roonlabs.net/1/instantsearch2?q=agnes%20obe.&count=8&c=qobuz-de returned after 1452 ms, status code: 200
01/10 22:16:35 Debug: [easyhttp] [1714] POST to https://search.roonlabs.net/1/instantsearch2?q=agnes%20obe&count=8&c=qobuz-de returned after 1496 ms, status code: 200
01/10 22:16:36 Debug: [easyhttp] [1715] POST to https://search.roonlabs.net/1/instantsearch2?q=agnes%20obel&count=8&c=qobuz-de returned after 2193 ms, status code: 200
01/10 22:16:37 Debug: [easyhttp] [1710] POST to https://search.roonlabs.net/1/instantsearch2?q=agnes&count=8&c=qobuz-de returned after 5138 ms, status code: 200
01/10 22:16:38 Debug: [easyhttp] [1707] POST to https://search.roonlabs.net/1/instantsearch2?q=ag&count=8&c=qobuz-de canceled after 7849 ms
01/10 22:16:39 Debug: [easyhttp] [1708] POST to https://search.roonlabs.net/1/instantsearch2?q=agn&count=8&c=qobuz-de canceled after 7987 ms
01/10 22:16:39 Debug: [easyhttp] [1709] POST to https://search.roonlabs.net/1/instantsearch2?q=agne&count=8&c=qobuz-de canceled after 7994 ms
01/10 22:16:51 Debug: [easyhttp] [1725] POST to https://search.roonlabs.net/1/instantsearch2?q=ai&count=8&c=qobuz-de canceled after 7945 ms
01/10 22:16:51 Debug: [easyhttp] [1727] POST to https://search.roonlabs.net/1/instantsearch2?q=aimee&count=8&c=qobuz-de returned after 7706 ms, status code: 200
01/10 22:16:51 Debug: [easyhttp] [1726] POST to https://search.roonlabs.net/1/instantsearch2?q=aime&count=8&c=qobuz-de canceled after 7985 ms
01/10 22:16:51 Debug: [easyhttp] [1728] POST to https://search.roonlabs.net/1/instantsearch2?q=aimee&count=8&c=qobuz-de canceled after 7995 ms
01/10 22:16:52 Debug: [easyhttp] [1731] POST to https://search.roonlabs.net/1/instantsearch2?q=aimee%20mann&count=8&c=qobuz-de returned after 5330 ms, status code: 200

Karsten_Feilke · January 19, 2023, 6:03pm

Any other ideas?

zenit · January 20, 2023, 6:24pm

Hello @Karsten_Feilke

There have been some recent changes that should address the issue you’re having, but unfortunately, they will only be part of the next release. There are also a few updates in the 1193 version that might help.

Would you mind uploading your DB when you get the chance? This way I can verify if the issue has been actually fixed for you.

The link for it is here Request User Data - Zoho WorkDrive (zohoexternal.com)

And here are are the instructions for it https://help.roonlabs.com/portal/en/kb/articles/upload-database#If_using_Roon_standalone_app_All-in-one

Karsten_Feilke · January 26, 2023, 2:58pm

Hi Zenit, my Roon core is on 1193 and the remotes are all on latest and greatest.
The problem still persists for now. Will these changes go into the next public release?
I am happy to wait und uploading the DB is something I would like to avoid as the DB
has 7-8GB and my upstream is quite slow.

Karsten_Feilke · February 1, 2023, 7:50am

Hi Zenit,

just upgraded my Core and Remote to 1202, restarted both, internet connection is idling and my subjective feeling is that queries are now a lot faster if these don’t fail. So the ones that are getting through are coming back after 0,5-2s (much better than before, examples “Tom Odell”, “Alexa Feser”). Others do unfortunately still time out after 8s (examples “Michael Jackson” or “James Arthur”). Queries that worked once do typically always work, the ones that fail do tend to always fail.

02/01 08:38:13 Debug: [easyhttp] [310] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Odell&count=8&c=qobuz-de returned after 1544 ms, status code: 200, request body size: 82 KB
02/01 08:38:13 Debug: [easyhttp] [309] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Odel&count=8&c=qobuz-de returned after 2086 ms, status code: 200, request body size: 82 KB
02/01 08:38:14 Debug: [easyhttp] [308] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Ode&count=8&c=qobuz-de returned after 2397 ms, status code: 200, request body size: 93 KB
02/01 08:38:14 Debug: [easyhttp] [306] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20O&count=8&c=qobuz-de returned after 2850 ms, status code: 200, request body size: 94 KB
02/01 08:38:18 Debug: [easyhttp] [303] POST to https://search.roonlabs.net/1/instantsearch2?q=To&count=8&c=qobuz-de canceled after 7749 ms
02/01 08:38:18 Debug: [easyhttp] [307] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Od&count=8&c=qobuz-de returned after 7066 ms, status code: 200, request body size: 423 KB
02/01 08:38:18 Debug: [easyhttp] [304] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7891 ms
02/01 08:38:18 Debug: [easyhttp] [305] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7951 ms
02/01 08:38:20 Debug: [easyhttp] [317] POST to https://search.roonlabs.net/1/instantsearch2?q=Beatles&count=8&c=qobuz-de returned after 1245 ms, status code: 200, request body size: 96 KB
02/01 08:38:20 Debug: [easyhttp] [316] POST to https://search.roonlabs.net/1/instantsearch2?q=Beatle&count=8&c=qobuz-de returned after 1629 ms, status code: 200, request body size: 96 KB
02/01 08:38:25 Debug: [easyhttp] [315] POST to https://search.roonlabs.net/1/instantsearch2?q=Bea&count=8&c=qobuz-de returned after 7392 ms, status code: 200, request body size: 529 KB
02/01 08:38:25 Debug: [easyhttp] [314] POST to https://search.roonlabs.net/1/instantsearch2?q=Be&count=8&c=qobuz-de canceled after 7766 ms
02/01 08:38:33 Debug: [easyhttp] [318] POST to https://search.roonlabs.net/1/instantsearch2?q=Ale&count=8&c=qobuz-de canceled after 7933 ms
02/01 08:38:33 Debug: [easyhttp] [319] POST to https://search.roonlabs.net/1/instantsearch2?q=Alex&count=8&c=qobuz-de canceled after 7933 ms
02/01 08:38:33 Debug: [easyhttp] [320] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de canceled after 7942 ms
02/01 08:38:34 Debug: [easyhttp] [321] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de canceled after 7947 ms
02/01 08:38:40 Debug: [easyhttp] [322] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de returned after 4127 ms, status code: 200, request body size: 638 KB
02/01 08:38:49 Debug: [easyhttp] [335] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fas&count=8&c=qobuz-de returned after 656 ms, status code: 200, request body size: 720 B
02/01 08:38:50 Debug: [easyhttp] [336] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Faser&count=8&c=qobuz-de returned after 998 ms, status code: 200, request body size: 172 B
02/01 08:38:50 Debug: [easyhttp] [333] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20F&count=8&c=qobuz-de returned after 2014 ms, status code: 200, request body size: 104 KB
02/01 08:38:52 Debug: [easyhttp] [337] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fase&count=8&c=qobuz-de returned after 593 ms, status code: 200, request body size: 172 B
02/01 08:38:53 Debug: [easyhttp] [338] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fas&count=8&c=qobuz-de returned after 553 ms, status code: 200, request body size: 720 B
02/01 08:38:53 Debug: [easyhttp] [340] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fers&count=8&c=qobuz-de returned after 605 ms, status code: 200, request body size: 172 B
02/01 08:38:54 Debug: [easyhttp] [341] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Ferse&count=8&c=qobuz-de returned after 599 ms, status code: 200, request body size: 172 B
02/01 08:38:54 Debug: [easyhttp] [342] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fers&count=8&c=qobuz-de returned after 806 ms, status code: 200, request body size: 172 B
02/01 08:38:55 Debug: [easyhttp] [329] POST to https://search.roonlabs.net/1/instantsearch2?q=Ale&count=8&c=qobuz-de canceled after 7929 ms
02/01 08:38:55 Debug: [easyhttp] [343] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fes&count=8&c=qobuz-de returned after 1105 ms, status code: 200, request body size: 71 KB
02/01 08:38:55 Debug: [easyhttp] [344] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fese&count=8&c=qobuz-de returned after 995 ms, status code: 200, request body size: 35 KB
02/01 08:38:55 Debug: [easyhttp] [330] POST to https://search.roonlabs.net/1/instantsearch2?q=Alex&count=8&c=qobuz-de canceled after 7937 ms
02/01 08:38:55 Debug: [easyhttp] [345] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Feser&count=8&c=qobuz-de returned after 858 ms, status code: 200, request body size: 35 KB
02/01 08:38:55 Debug: [easyhttp] [331] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:38:56 Debug: [easyhttp] [332] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:38:56 Debug: [easyhttp] [334] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fa&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:38:57 Debug: [easyhttp] [339] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fer&count=8&c=qobuz-de returned after 4581 ms, status code: 200, request body size: 293 KB
02/01 08:39:06 Debug: [easyhttp] [349] POST to https://search.roonlabs.net/1/instantsearch2?q=Jame&count=8&c=qobuz-de canceled after 7844 ms
02/01 08:39:07 Debug: [easyhttp] [350] POST to https://search.roonlabs.net/1/instantsearch2?q=James&count=8&c=qobuz-de canceled after 7931 ms
02/01 08:39:07 Debug: [easyhttp] [351] POST to https://search.roonlabs.net/1/instantsearch2?q=James%20A&count=8&c=qobuz-de canceled after 7918 ms
02/01 08:39:07 Debug: [easyhttp] [352] POST to https://search.roonlabs.net/1/instantsearch2?q=James%20Ar&count=8&c=qobuz-de canceled after 7852 ms
02/01 08:39:08 Debug: [easyhttp] [353] POST to https://search.roonlabs.net/1/instantsearch2?q=James%20Arth&count=8&c=qobuz-de canceled after 7970 ms
02/01 08:39:08 Debug: [easyhttp] [354] POST to https://search.roonlabs.net/1/instantsearch2?q=James%20Arthu&count=8&c=qobuz-de canceled after 7976 ms
02/01 08:39:08 Debug: [easyhttp] [355] POST to https://search.roonlabs.net/1/instantsearch2?q=James%20Arthur&count=8&c=qobuz-de canceled after 7977 ms
02/01 08:39:19 Debug: [easyhttp] [356] POST to https://search.roonlabs.net/1/instantsearch2?q=James%20Arthu&count=8&c=qobuz-de canceled after 7978 ms
02/01 08:39:28 Debug: [easyhttp] [359] POST to https://search.roonlabs.net/1/instantsearch2?q=P%21nk&count=8&c=qobuz-de returned after 5200 ms, status code: 200, request body size: 283 KB
02/01 08:39:28 Debug: [easyhttp] [358] POST to https://search.roonlabs.net/1/instantsearch2?q=P%21n&count=8&c=qobuz-de returned after 5886 ms, status code: 200, request body size: 283 KB
02/01 08:39:29 Debug: [easyhttp] [357] POST to https://search.roonlabs.net/1/instantsearch2?q=P%21&count=8&c=qobuz-de returned after 7203 ms, status code: 200, request body size: 283 KB
02/01 08:39:44 Debug: [easyhttp] [360] POST to https://search.roonlabs.net/1/instantsearch2?q=Micha&count=8&c=qobuz-de canceled after 7939 ms
02/01 08:39:44 Debug: [easyhttp] [361] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8&c=qobuz-de canceled after 7940 ms
02/01 08:39:44 Debug: [easyhttp] [362] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8&c=qobuz-de canceled after 7940 ms
02/01 08:39:44 Debug: [easyhttp] [363] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Ja&count=8&c=qobuz-de canceled after 7902 ms
02/01 08:39:45 Debug: [easyhttp] [364] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jacks&count=8&c=qobuz-de canceled after 7951 ms
02/01 08:39:45 Debug: [easyhttp] [365] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackso&count=8&c=qobuz-de canceled after 7961 ms
02/01 08:39:45 Debug: [easyhttp] [366] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackson&count=8&c=qobuz-de canceled after 7961 ms
02/01 08:39:55 Debug: [easyhttp] [372] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Odell&count=8&c=qobuz-de returned after 1599 ms, status code: 200, request body size: 82 KB
02/01 08:39:55 Debug: [easyhttp] [371] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Odel&count=8&c=qobuz-de returned after 1770 ms, status code: 200, request body size: 82 KB
02/01 08:40:00 Debug: [easyhttp] [367] POST to https://search.roonlabs.net/1/instantsearch2?q=To&count=8&c=qobuz-de canceled after 7820 ms
02/01 08:40:00 Debug: [easyhttp] [368] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:40:00 Debug: [easyhttp] [369] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7711 ms
02/01 08:40:01 Debug: [easyhttp] [370] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Od&count=8&c=qobuz-de canceled after 7976 ms
02/01 08:40:06 Debug: [easyhttp] [373] POST to https://search.roonlabs.net/1/instantsearch2?q=Micha&count=8&c=qobuz-de canceled after 7934 ms
02/01 08:40:07 Debug: [easyhttp] [374] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8&c=qobuz-de canceled after 7937 ms
02/01 08:40:07 Debug: [easyhttp] [375] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8&c=qobuz-de canceled after 7941 ms
02/01 08:40:07 Debug: [easyhttp] [376] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Ja&count=8&c=qobuz-de canceled after 7901 ms
02/01 08:40:08 Debug: [easyhttp] [377] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackso&count=8&c=qobuz-de canceled after 7952 ms
02/01 08:40:08 Debug: [easyhttp] [378] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackson&count=8&c=qobuz-de canceled after 7959 ms
02/01 08:40:13 Debug: [easyhttp] [382] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Ode&count=8&c=qobuz-de returned after 1768 ms, status code: 200, request body size: 93 KB
02/01 08:40:14 Debug: [easyhttp] [383] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Odell&count=8&c=qobuz-de returned after 1842 ms, status code: 200, request body size: 82 KB
02/01 08:40:19 Debug: [easyhttp] [379] POST to https://search.roonlabs.net/1/instantsearch2?q=To&count=8&c=qobuz-de canceled after 7815 ms
02/01 08:40:19 Debug: [easyhttp] [380] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:40:19 Debug: [easyhttp] [381] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7951 ms
02/01 08:40:26 Debug: [easyhttp] [390] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fas&count=8&c=qobuz-de returned after 537 ms, status code: 200, request body size: 720 B
02/01 08:40:27 Debug: [easyhttp] [388] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20F&count=8&c=qobuz-de returned after 2997 ms, status code: 200, request body size: 104 KB
02/01 08:40:28 Debug: [easyhttp] [394] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Feser&count=8&c=qobuz-de returned after 1192 ms, status code: 200, request body size: 35 KB
02/01 08:40:28 Debug: [easyhttp] [393] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fes&count=8&c=qobuz-de returned after 1302 ms, status code: 200, request body size: 71 KB
02/01 08:40:30 Debug: [easyhttp] [384] POST to https://search.roonlabs.net/1/instantsearch2?q=Ale&count=8&c=qobuz-de canceled after 7925 ms
02/01 08:40:30 Debug: [easyhttp] [385] POST to https://search.roonlabs.net/1/instantsearch2?q=Alex&count=8&c=qobuz-de canceled after 7936 ms
02/01 08:40:31 Debug: [easyhttp] [386] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de canceled after 7945 ms
02/01 08:40:31 Debug: [easyhttp] [387] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa&count=8&c=qobuz-de canceled after 7945 ms
02/01 08:40:33 Debug: [easyhttp] [389] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fa&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:40:33 Debug: [easyhttp] [399] POST to https://search.roonlabs.net/1/instantsearch2?q=Frida%20G&count=8&c=qobuz-de returned after 1076 ms, status code: 200, request body size: 172 B
02/01 08:40:34 Debug: [easyhttp] [401] POST to https://search.roonlabs.net/1/instantsearch2?q=Frida%20Gold&count=8&c=qobuz-de returned after 943 ms, status code: 200, request body size: 20 KB
02/01 08:40:34 Debug: [easyhttp] [391] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fa&count=8&c=qobuz-de canceled after 7955 ms
02/01 08:40:34 Debug: [easyhttp] [400] POST to https://search.roonlabs.net/1/instantsearch2?q=Frida%20Go&count=8&c=qobuz-de returned after 1447 ms, status code: 200, request body size: 68 KB
02/01 08:40:34 Debug: [easyhttp] [392] POST to https://search.roonlabs.net/1/instantsearch2?q=Alexa%20Fe&count=8&c=qobuz-de canceled after 7951 ms
02/01 08:40:39 Debug: [easyhttp] [395] POST to https://search.roonlabs.net/1/instantsearch2?q=Fri&count=8&c=qobuz-de canceled after 7943 ms
02/01 08:40:40 Debug: [easyhttp] [396] POST to https://search.roonlabs.net/1/instantsearch2?q=Frid&count=8&c=qobuz-de canceled after 7969 ms
02/01 08:40:40 Debug: [easyhttp] [397] POST to https://search.roonlabs.net/1/instantsearch2?q=Frida&count=8&c=qobuz-de canceled after 7993 ms
02/01 08:40:40 Debug: [easyhttp] [398] POST to https://search.roonlabs.net/1/instantsearch2?q=Frida&count=8&c=qobuz-de canceled after 7995 ms
02/01 08:40:41 Debug: [easyhttp] [409] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20G&count=8&c=qobuz-de returned after 1211 ms, status code: 200, request body size: 26 KB
02/01 08:40:42 Debug: [easyhttp] [411] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6ne&count=8&c=qobuz-de returned after 1028 ms, status code: 200, request body size: 43 KB
02/01 08:40:42 Debug: [easyhttp] [412] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6nemei&count=8&c=qobuz-de returned after 611 ms, status code: 200, request body size: 172 B
02/01 08:40:43 Debug: [easyhttp] [413] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6nemeie&count=8&c=qobuz-de returned after 842 ms, status code: 200, request body size: 172 B
02/01 08:40:44 Debug: [easyhttp] [414] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6nemei&count=8&c=qobuz-de returned after 557 ms, status code: 200, request body size: 172 B
02/01 08:40:44 Debug: [easyhttp] [415] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6neme&count=8&c=qobuz-de returned after 902 ms, status code: 200, request body size: 43 KB
02/01 08:40:45 Debug: [easyhttp] [416] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6nema&count=8&c=qobuz-de returned after 763 ms, status code: 200, request body size: 172 B
02/01 08:40:46 Debug: [easyhttp] [417] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6nem&count=8&c=qobuz-de returned after 889 ms, status code: 200, request body size: 43 KB
02/01 08:40:47 Debug: [easyhttp] [406] POST to https://search.roonlabs.net/1/instantsearch2?q=Herber&count=8&c=qobuz-de canceled after 7968 ms
02/01 08:40:47 Debug: [easyhttp] [419] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Groe&count=8&c=qobuz-de returned after 1046 ms, status code: 200, request body size: 42 KB
02/01 08:40:47 Debug: [easyhttp] [407] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert&count=8&c=qobuz-de canceled after 7977 ms
02/01 08:40:48 Debug: [easyhttp] [408] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert&count=8&c=qobuz-de canceled after 7978 ms
02/01 08:40:49 Debug: [easyhttp] [410] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr%C3%B6&count=8&c=qobuz-de canceled after 7978 ms
02/01 08:40:54 Debug: [easyhttp] [418] POST to https://search.roonlabs.net/1/instantsearch2?q=Herbert%20Gr&count=8&c=qobuz-de canceled after 7946 ms
02/01 08:40:55 Debug: [easyhttp] [429] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Bentz&count=8&c=qobuz-de returned after 676 ms, status code: 200, request body size: 172 B
02/01 08:40:56 Debug: [easyhttp] [432] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Bendz&count=8&c=qobuz-de returned after 890 ms, status code: 200, request body size: 32 KB
02/01 08:40:59 Debug: [easyhttp] [437] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Bendzk&count=8&c=qobuz-de returned after 929 ms, status code: 200, request body size: 32 KB
02/01 08:40:59 Debug: [easyhttp] [438] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Bendzko&count=8&c=qobuz-de returned after 874 ms, status code: 200, request body size: 32 KB
02/01 08:41:00 Debug: [easyhttp] [424] POST to https://search.roonlabs.net/1/instantsearch2?q=Ti&count=8&c=qobuz-de canceled after 7901 ms
02/01 08:41:00 Debug: [easyhttp] [425] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim&count=8&c=qobuz-de canceled after 7925 ms
02/01 08:41:01 Debug: [easyhttp] [426] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim&count=8&c=qobuz-de canceled after 7928 ms
02/01 08:41:02 Debug: [easyhttp] [427] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20B&count=8&c=qobuz-de canceled after 7968 ms
02/01 08:41:02 Debug: [easyhttp] [428] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Ben&count=8&c=qobuz-de canceled after 7936 ms
02/01 08:41:03 Debug: [easyhttp] [430] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Bent&count=8&c=qobuz-de canceled after 7981 ms
02/01 08:41:03 Debug: [easyhttp] [431] POST to https://search.roonlabs.net/1/instantsearch2?q=Tim%20Bend&count=8&c=qobuz-de canceled after 7979 ms
02/01 08:41:06 Debug: [easyhttp] [447] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Odell&count=8&c=qobuz-de returned after 1120 ms, status code: 200, request body size: 82 KB
02/01 08:41:07 Debug: [easyhttp] [445] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20O&count=8&c=qobuz-de returned after 2381 ms, status code: 200, request body size: 94 KB
02/01 08:41:11 Debug: [easyhttp] [442] POST to https://search.roonlabs.net/1/instantsearch2?q=To&count=8&c=qobuz-de canceled after 7817 ms
02/01 08:41:12 Debug: [easyhttp] [443] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7942 ms
02/01 08:41:12 Debug: [easyhttp] [444] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom&count=8&c=qobuz-de canceled after 7954 ms
02/01 08:41:12 Debug: [easyhttp] [446] POST to https://search.roonlabs.net/1/instantsearch2?q=Tom%20Od&count=8&c=qobuz-de canceled after 7975 ms
02/01 08:41:17 Debug: [easyhttp] [448] POST to https://search.roonlabs.net/1/instantsearch2?q=Micha&count=8&c=qobuz-de canceled after 7855 ms
02/01 08:41:17 Debug: [easyhttp] [449] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8&c=qobuz-de canceled after 7928 ms
02/01 08:41:17 Debug: [easyhttp] [450] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Ja&count=8&c=qobuz-de canceled after 7902 ms
02/01 08:41:18 Debug: [easyhttp] [451] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jack&count=8&c=qobuz-de canceled after 7949 ms
02/01 08:41:18 Debug: [easyhttp] [452] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackso&count=8&c=qobuz-de canceled after 7959 ms
02/01 08:41:18 Debug: [easyhttp] [453] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackson&count=8&c=qobuz-de canceled after 7960 ms

Another observation: when I query Michael Jackson Roon times out, searching for the album “Thriller” always gets a 200:

02/01 08:53:26 Debug: [easyhttp] [625] POST to https://search.roonlabs.net/1/instantsearch2?q=micha&count=8&c=qobuz-de canceled after 7932 ms
02/01 08:53:26 Debug: [search] query 'micha' cancelled.
02/01 08:53:27 Debug: [easyhttp] [626] POST to https://search.roonlabs.net/1/instantsearch2?q=michael&count=8&c=qobuz-de canceled after 7934 ms
02/01 08:53:27 Debug: [search] query 'michael' cancelled.
02/01 08:53:27 Debug: [easyhttp] [627] POST to https://search.roonlabs.net/1/instantsearch2?q=michael%20ja&count=8&c=qobuz-de canceled after 7902 ms
02/01 08:53:27 Debug: [search] query 'michael ja' cancelled.
02/01 08:53:28 Debug: [easyhttp] [628] POST to https://search.roonlabs.net/1/instantsearch2?q=michael%20jackso&count=8&c=qobuz-de canceled after 7952 ms
02/01 08:53:28 Debug: [search] query 'michael jackso' cancelled.
02/01 08:53:28 Debug: [easyhttp] [629] POST to https://search.roonlabs.net/1/instantsearch2?q=michael%20jackson&count=8&c=qobuz-de canceled after 7960 ms
02/01 08:53:28 Debug: [search] query 'michael jackson' cancelled.
02/01 08:53:31 Info: UnifiedAutocomplete
02/01 08:53:31 Info:     ProfileId=63:1:0dff2df0-b976-4806-aabf-3190682e55fe
02/01 08:53:31 Info:     Terms=thri
02/01 08:53:31 Info:     MaxCount=8
02/01 08:53:31 Info:     IncludeHidden=False
02/01 08:53:31 Info:     CancelKey=c7ec2bb69de84962848e1bf1f714edc1
02/01 08:53:31 Info:     RequestTimeoutInMs=8000
02/01 08:53:32 Info: UnifiedAutocomplete
02/01 08:53:32 Info:     ProfileId=63:1:0dff2df0-b976-4806-aabf-3190682e55fe
02/01 08:53:32 Info:     Terms=thriller
02/01 08:53:32 Info:     MaxCount=8
02/01 08:53:32 Info:     IncludeHidden=False
02/01 08:53:32 Info:     CancelKey=3adef5a21152449a905b7a0d993d2d50
02/01 08:53:32 Info:     RequestTimeoutInMs=8000
02/01 08:53:33 Debug: [easyhttp] [631] POST to https://search.roonlabs.net/1/instantsearch2?q=thriller&count=8&c=qobuz-de returned after 1355 ms, status code: 200, request body size: 151 KB
02/01 08:53:34 Info: [stats] 21736mb Virtual, 5169mb Physical, 2436mb Managed, 373 Handles, 106 Threads
02/01 08:53:36 Debug: [easyhttp] [630] POST to https://search.roonlabs.net/1/instantsearch2?q=thri&count=8&c=qobuz-de returned after 5348 ms, status code: 200, request body size: 198 KB

Noticed that I reneabled Qobuz, disabled Qobuz and tested again but it is still the same behavior:

02/01 09:02:48 Debug: [easyhttp] [635] POST to https://search.roonlabs.net/1/instantsearch2?q=Micha&count=8 canceled after 7939 ms
02/01 09:02:48 Debug: [search] query 'Micha' cancelled.
02/01 09:02:48 Debug: [easyhttp] [636] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8 canceled after 7943 ms
02/01 09:02:48 Debug: [search] query 'Michael' cancelled.
02/01 09:02:48 Debug: [easyhttp] [637] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael&count=8 canceled after 7940 ms
02/01 09:02:48 Debug: [search] query 'Michael' cancelled.
02/01 09:02:48 Debug: [easyhttp] [638] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Ja&count=8 canceled after 7903 ms
02/01 09:02:48 Debug: [search] query 'Michael Ja' cancelled.
02/01 09:02:49 Debug: [easyhttp] [639] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jac&count=8 canceled after 7949 ms
02/01 09:02:49 Debug: [search] query 'Michael Jac' cancelled.
02/01 09:02:49 Debug: [easyhttp] [640] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jack&count=8 canceled after 7957 ms
02/01 09:02:49 Debug: [search] query 'Michael Jack' cancelled.
02/01 09:02:49 Debug: [easyhttp] [642] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackso&count=8 canceled after 7961 ms
02/01 09:02:49 Debug: [search] query 'Michael Jackso' cancelled.
02/01 09:02:50 Info: [stats] 21786mb Virtual, 5039mb Physical, 2618mb Managed, 374 Handles, 111 Threads
02/01 09:02:50 Debug: [easyhttp] [643] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackson&count=8 canceled after 7961 ms
02/01 09:02:50 Debug: [search] query 'Michael Jackson' cancelled.
02/01 09:02:57 Debug: [easyhttp] [644] POST to https://search.roonlabs.net/1/search2?q=Michael%20Jackson&count=50 timed out after 15000 ms
02/01 09:02:57 Info: [search] Successful search for query:'Michael Jackson'
02/01 09:03:05 Info: [stats] 21657mb Virtual, 5039mb Physical, 2639mb Managed, 372 Handles, 96 Threads
02/01 09:03:06 Info: UnifiedAutocomplete
02/01 09:03:06 Info:     ProfileId=63:1:0dff2df0-b976-4806-aabf-3190682e55fe
02/01 09:03:06 Info:     Terms=Michael Jackson
02/01 09:03:06 Info:     MaxCount=8
02/01 09:03:06 Info:     IncludeHidden=False
02/01 09:03:06 Info:     CancelKey=4234f644f13b47aea1c7c70792ea90ef
02/01 09:03:06 Info:     RequestTimeoutInMs=8000
02/01 09:03:14 Debug: [easyhttp] [646] POST to https://search.roonlabs.net/1/instantsearch2?q=Michael%20Jackson&count=8 canceled after 7962 ms
02/01 09:03:14 Debug: [search] query 'Michael Jackson' cancelled.
02/01 09:03:17 Info: UnifiedAutocomplete
02/01 09:03:17 Info:     ProfileId=63:1:0dff2df0-b976-4806-aabf-3190682e55fe
02/01 09:03:17 Info:     Terms=Th
02/01 09:03:17 Info:     MaxCount=8
02/01 09:03:17 Info:     IncludeHidden=False
02/01 09:03:17 Info:     CancelKey=d48572bf8fb84dd6a0b0de39b56481fc
02/01 09:03:17 Info:     RequestTimeoutInMs=8000
02/01 09:03:17 Info: UnifiedAutocomplete
02/01 09:03:17 Info:     ProfileId=63:1:0dff2df0-b976-4806-aabf-3190682e55fe
02/01 09:03:17 Info:     Terms=Thri
02/01 09:03:17 Info:     MaxCount=8
02/01 09:03:17 Info:     IncludeHidden=False
02/01 09:03:17 Info:     CancelKey=1081895fb51e4ad1af65f252ac871ace
02/01 09:03:17 Info:     RequestTimeoutInMs=8000
02/01 09:03:18 Info: UnifiedAutocomplete
02/01 09:03:18 Info:     ProfileId=63:1:0dff2df0-b976-4806-aabf-3190682e55fe
02/01 09:03:18 Info:     Terms=Thriller
02/01 09:03:18 Info:     MaxCount=8
02/01 09:03:18 Info:     IncludeHidden=False
02/01 09:03:18 Info:     CancelKey=f5cf49963b5141c6b22488cb6ecfb151
02/01 09:03:18 Info:     RequestTimeoutInMs=8000
02/01 09:03:20 Info: [stats] 22315mb Virtual, 4968mb Physical, 2660mb Managed, 375 Handles, 178 Threads
02/01 09:03:20 Debug: [easyhttp] [649] POST to https://search.roonlabs.net/1/instantsearch2?q=Thriller&count=8 returned after 2732 ms, status code: 200, request body size: 151 KB
02/01 09:03:21 Debug: [easyhttp] [648] POST to https://search.roonlabs.net/1/instantsearch2?q=Thri&count=8 returned after 3406 ms, status code: 200, request body size: 198 KB

zenit · February 1, 2023, 3:45pm

How slow exactly? This might also affect the queries.

Sorry to hear that you’re still facing these issues. How many tracks do you have in your library?

Karsten_Feilke · February 1, 2023, 4:56pm

Hi Zenit,

16Mbit down / 3Mbit up - definitely not excellent but it should be good enough to send a text query, right?

Did a couple of tests and the instant search queries do indeed stress the upstream with a lot of data and queries that fail are on the bigger side.

Ok, let’s check my WAN Port:

Now sending an “Alexa Faser” query: this was successful / took 1-2s
Sent 7,5 Megabyte!! to query for Alexa Faser and gets almost no data back.

Now sending a “Michael Jackson” query: this failed / timed out after 8s
Sent 15 Megabyte of payload! And again, there is almost nothing coming back.

Now sending a “Tom Odel” query: this came back after 1s

Now sending a “James Arthur” query: timed out

Now sending a “Herbert Grönemeyer” query: successful in 1s

And finally the “History” query: <1s as well

Karsten_Feilke · February 1, 2023, 5:01pm

I have the impression that longer search phrases come with more queries (auto completion) and Roon sends in general quite a lot of payload! What is this data? Isn’t it possible to make this more efficient? Or to run a local query?

Karsten_Feilke · February 1, 2023, 5:05pm

And queries like “Alexa Faser”, “Tom Odell” and “Herbert Grönemeyer” all successfully came back after 1s even if Roon was sending data 10-30s.

Karsten_Feilke · February 1, 2023, 5:23pm

Checked the Server CPU and Disk Utilization as well. This is the results for 15-20 queries:

Disk System: Raid group 1 is the SSD cache (1000MB/s+), Raid group 2 is a traditional raid 5 (300MB/s+). Running at 0,5MB/s = idling.

CPU Utilization < 10%.

Except for these instand queries Roon is fast as hell. Even importing / analyzing new albums takes seconds.

Rugby · February 1, 2023, 5:37pm

How much of each query do you have in local files. Meaning, do you have a lot of local Michael Jackson files compared to library content of other searches?

I’m guessing the new “search” is sending a list of what you have locally, so it can integrate it on their backend, and then return the search back again.

zenit · February 1, 2023, 5:39pm

That’s definitely sufficient.

Can you please answer this? I’d like to try to reproduce the issues you’re experiencing but without the DB I need to find out how to do so.

There seems to be something peculiar in your system that is causing the issue. Do you have f.e. large box sets, or like @Rugby mentioned above, a huge Michael Jackson collection?

Karsten_Feilke · February 1, 2023, 7:36pm

We are getting closer - if there is

1 album / appr 10-15 tracks: queries do almost always succeed, most come back quickly, some take 2-4s
5 albums / 50 tracks: typically works as well
James Arthur is an exception. There is just one album but when I filter the list of artists by james, then I am getting 40 hits. Maybe this is also a problem.
10 - 20 albums / 100-300 tracks: always fails

Karsten_Feilke · February 6, 2023, 9:39am

Yesterday I was talking to a friend about a few tracks and I tried to find these with ‘instant search’ and even 2-3s (if it works) are super annoying. Finally I brought up the track view, filtered by artist and then by track. This has absolutely no latency but it is not very convenient (especially when you want to query a couple of songs).

Not sure how Roon builds the instant query but I assume that most of your customers are using Roon primarily to listen to their own library. I would love to see a toggle button that makes the instant search offline (next to the field itself). Would be good enough if this search across the artists, albums and tracks in my local library. If possible a fuzzy search but strict would be already a good 1st step.

I like your instant search but if this does no go through in < 1s I would definitely always use a super fast local engine if I would have a choice. Another option would be to add search capabilities to the track view - a search field that looks at artists, albums and tracks.

I wasn’t expecting the amount of data the instant query sends to the server. Why does this query attach MBs of data? What is in these blobs?

Rugby · February 6, 2023, 2:32pm

Yep, it seems like a lot of data.