Slow Search Issues

When it happens again I will.

1 Like

@noris Just had search slow down between 19.05-19:15 GMT -

DId search for Beatles took nearly a minute to happen, then everything else was within a few seconds. I do notice that this always seems to happen when searching and playing a song.

FYI it looks like Roonserver is not accounting for DST as its about an hour behind what it is in the UK.

Also looking at RoonServer log it shows the search I did for The Beatles taking 34ms which is no time at all, yet displaying the results to the windows remote took about a minute. So the logs dont show anything related that I can see and do not tally to what is actually happening. Before the The Beatles search I was searching for London Grammar which is where it started to get slow, I then followed your search parameters and that was worse for the initial seach of The Beatles. But none of this is in the log as showing any delay. So could the issue be the remote side and not the server side?

Hi @CrystalGipsy,

Thank you for sharing that information, I have added it to QA’s investigation.

This would be because ROCK uses UTC time, which is one hour behind UK time.

I too believe this to be the case. We have a few changes regarding search coming in our next Roon release, and while I can’t comment on a specific timeline of when it will be published, I am hopeful that your results here will help QA locate any possible remote side sluggishness.

Let’s please revisit this topic once the next release is out and see if the same behavior occurs.

Thanks,
Noris

Hi @CrystalGipsy,

I wanted to reach out because we’ve just released Roon 1.6 (Build 416). This update includes a fix for some performance problems we believe may be causing some users to experience slowness in Roon. Please give Roon an update and let us know if things have improved for you.

You can read the full release notes here:

1 Like

Yes I saw the update this morning. Not had chance to use it will feedback when I have. Hopefully this is the root of the issue and all will be good again.

1 Like

Sorry @dylan @noris looks like I am still getting some slowness. It seems improved but still slower than expected and what I was getting before 1.6. Average search returned in 4-5 with some longer at around 6-7. Its also still very variable. This is via windows or Android phone remotes both use 5ghz wireless. Will keep an eye to see if it maintains this level and does not get to the exceptionally long delays I was getting,

Hi @CrystalGipsy,

Glad to hear that the update has helped and the long search delays appear to have been resolved. Search in general now works a bit different than before 1.6, simply because search now depends on our cloud services, see Mike’s comment here for more info:

Please do continue to monitor the situation and let us know how it goes moving forward.

– Noris

Searches getting slower this evening. 19:15 ish GMT video link here will expire by 30th

https://1drv.ms/u/s!AgHQUEVFVR_nx7oMzB6b-0PdT6GqFg

Hi @CrystalGipsy,

It looks like it took ~ 6 seconds for the search to complete. We would like obviously like to make this snappier and as fast as we can, this doesn’t look too alarming to me. I would suggest trying it out over the weekend and see how things go.

– Noris

Hmm it’s slow compared to everything else, Tidal, Qobuz and Bluesound apps are way quicker. Anyways just had a long delay of over 30secs or more searching for London Grammar at around 15:32 GMT. I found switching endpoint made it come back to life.

1 Like

Getting slower the more I search. This is certainly not fixed imo. Did there searches for Beatles then search for something else searched The Beatles again and it took ages to display the albums again even though I had looked at them recently.

1 Like

Hi @CrystalGipsy,

Thanks for the feedback here.

We have seen quite a few reports that the performance changes in our most recent release has helped a lot of users but this appears to not be the case for you here.

I will to be discussing with the technical team on the best approach to troubleshoot this issue at our next meeting and will be sure to let you know what they say.

– Noris

Oh dear look at that slowness

4/30 18:49:35 Info: [stats] 3942mb Virtual, 1924mb Physical, 910mb Managed, 0 Handles, 69 Threads
04/30 18:49:35 Debug: [library] search finished in 127ms
04/30 18:49:35 Trace: [metadatasvc] REQ [11686] https://metadataserver.roonlabs.net/md/4/search2/albums?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50
04/30 18:49:35 Trace: [metadatasvc] REQ [11687] https://metadataserver.roonlabs.net/md/4/search2/tracks?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50
04/30 18:49:35 Trace: [metadatasvc] REQ [11688] https://metadataserver.roonlabs.net/md/4/search2/performers?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50
04/30 18:49:35 Trace: [metadatasvc] REQ [11689] https://metadataserver.roonlabs.net/md/4/search2/works?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50
04/30 18:49:35 Trace: [metadatasvc] REQ [11690] https://metadataserver.roonlabs.net/md/4/search2/composers?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50
04/30 18:49:35 Trace: [metadatasvc] GOT [11689] (273ms) Sooloos.Msg.Metadata.WorkLitesData: 6401fc0800b6fd680c16fefdb75ce2a52d0c, 6401fc0800b6fd680c16fefdb75ce2a52d0c, 640130989e7a755ff384630e3dded74afa11, 640130989e7a755ff384630e3dded74afa11, 6401ecf95b60e009269e0abdda5835fc23cf, 6401ecf95b60e009269e0abdda5835fc23cf, 6401c168f5c7f4e11a991376bc0a83932d56, 6401c168f5c7f4e11a991376bc0a83932d56, 64014d32625d08e541eb4194f0df04958f62, 64014d32625d08e541eb4194f0df04958f62, 640144314ff4b8eb12effa305b8c29d3f9aa, 640144314ff4b8eb12effa305b8c29d3f9aa, 6401b257427d0801893d1541a4f1c4e054db, 6401b257427d0801893d1541a4f1c4e054db, 6401c01b09b7927ed20c12eefed39e68743a, 6401c01b09b7927ed20c12eefed39e68743a, 640100cacc20721ecfc344deb869f81d0781, 640100cacc20721ecfc344deb869f81d0781, 6401de39700909414dbbb41b5bab4b7ecf7e, 6401de39700909414dbbb41b5bab4b7ecf7e, 64017cd4301bb8ba4f11e5be2244bb1b2f51, 64017cd4301bb8ba4f11e5be2244bb1b2f51, 6401afded685812bbf419e6022a4c78e9542, 6401afded685812bbf419e6022a4c78e9542, 6401101326b37e9a2a766592c6166c7ca04e, 6401101326b37e9a2a766592c6166c7ca04e, 6401825f0bebb1fc3cdd785bc7231d36fa9f, 6401825f0bebb1fc3cdd785bc7231d36fa9f, 6401f38fc4d29b0b349672416fd062e3e22b, 6401f38fc4d29b0b349672416fd062e3e22b, 640197caef1ae00b0f509d11606cd0decbfe, 640197caef1ae00b0f509d11606cd0decbfe, 640172149f310b0d766ac94df8005f13c920, 640172149f310b0d766ac94df8005f13c920, 6401633a6f541b5d120f7c4ab807db728db4, 6401633a6f541b5d120f7c4ab807db728db4, 640120c4fe4ce52b26e1da9eb65f16f0c097, 640120c4fe4ce52b26e1da9eb65f16f0c097, 6401fba0c0cdca54168d4233c05283d29d8f, 6401fba0c0cdca54168d4233c05283d29d8f, 6401eb4392ccd0dd6fd56ed6d3c73bfd6a2a, 6401eb4392ccd0dd6fd56ed6d3c73bfd6a2a, 640194cc705192529bf60da6707d17accb10, 640194cc705192529bf60da6707d17accb10, 64016bfe889318f965e69bf89e03872c1750, 64016bfe889318f965e69bf89e03872c1750, 6401bc8a22239b48c502055dd56f90775ed1, 6401bc8a22239b48c502055dd56f90775ed1, 640189f6e9c8b8c4199b86204abe882e8cb6, 640189f6e9c8b8c4199b86204abe882e8cb6, 64017ce8a1ef1a65ab21286f8b69b0e9dc2e, 64017ce8a1ef1a65ab21286f8b69b0e9dc2e, 640158adaafca2d53c26ab16a9c34233a7b6, 640158adaafca2d53c26ab16a9c34233a7b6, 6401b33b255dbba4a5bc04d68f042b379c74, 6401b33b255dbba4a5bc04d68f042b379c74, 64010ce4976ca170219bd3ddabe851dded00, 64010ce4976ca170219bd3ddabe851dded00, 6401ef254a739ce856798c669b2a33224349, 6401ef254a739ce856798c669b2a33224349, 640190555b46a3fc19f7904fe4eeae41490f, 640190555b46a3fc19f7904fe4eeae41490f, 6401edb1b4e5aabf173bd182d237c83be421, 6401edb1b4e5aabf173bd182d237c83be421, 6401c2bdf954dadb1f5e78d63ec1ac3dcf9a, 6401c2bdf954dadb1f5e78d63ec1ac3dcf9a, 6401945643e97dfd40f2df285bff499c292f, 6401945643e97dfd40f2df285bff499c292f, 64018f97c672ae7794ffe6b30f6a4c0c365b, 64018f97c672ae7794ffe6b30f6a4c0c365b, 6401e8d0fe9ec1d01541c1531f2c7e0b9d28, 6401e8d0fe9ec1d01541c1531f2c7e0b9d28, 640145899cf085c941b4f5607f615003325c, 6401b66d3f4fa37868903937cf9bbb27f859, 6401b66d3f4fa37868903937cf9bbb27f859, 64013a3f040bd7b9ab3662e157223d955fe7, 64013a3f040bd7b9ab3662e157223d955fe7, 6401cfd4f9f4631d2161c1777775e16c237c, 6401cfd4f9f4631d2161c1777775e16c237c, 64013de6a0edc67a8050da944c7f7d85988a, 64013de6a0edc67a8050da944c7f7d85988a, 6401723bb373983d67905871158adf48cabf, 6401723bb373983d67905871158adf48cabf, 6401e3166465172473fca6c70a4c6be4d971, 6401e3166465172473fca6c70a4c6be4d971, 6401a5d944bc026117715bd2ec9ffe79f812, 6401a5d944bc026117715bd2ec9ffe79f812, 6401e5e3598d840ef8bbb40b8a71bc3db61b, 6401e5e3598d840ef8bbb40b8a71bc3db61b, 64017310749e98363e54ba231c25bdaac562, 64017310749e98363e54ba231c25bdaac562, 640192c5cfef7a3aaaafbba161199caa73e0, 640192c5cfef7a3aaaafbba161199caa73e0, 64016c3b3c47e68c76cc90b7d440f28e5662, 64016c3b3c47e68c76cc90b7d440f28e5662, 64010ce4411913f426eaba4ec75c9ad62db8, 64010ce4411913f426eaba4ec75c9ad62db8, 64015609b02aac61f236cb99e6600c08a397, 64015609b02aac61f236cb99e6600c08a397
04/30 18:49:35 Debug: [music/search/performance] backend search of works took 425ms
04/30 18:49:38 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "DeviceChanged", "device": {"type": "asio", "device_id": "{64083009-BD64-42AA-ADA3-73F51E17CAD7}", "config": {"unique_id": "29e6429b-0922-dc0f-099b-6aa52924efd0", "output": {"type": "asio", "dsd_mode": "native", "name": "iFi (by AMR) HD USB Audio", "device": "{64083009-BD64-42AA-ADA3-73F51E17CAD7}"}, "external_config": {}}, "error_message": "DeviceOpenFailed", "name": "iFi (by AMR) HD USB Audio"}}
04/30 18:49:38 Trace: [metadatasvc] GOT [11686] (3107ms) Sooloos.Msg.Metadata.AlbumLitesData: a6003237313039333634, c80030303630323533373731373934, a6003138373130303533, c80030303630323532373939313031, a60035313738333231, a60038313632373030, c80030303630303434353032373132, a6003538323434393036, c80030313930333734343836393538, a600363139383037, c80030303733313435313237383832, a6003531383331313231, a600313037323637383039, a6003436333835323735, a6003834333334343331, a6003932363230343438, a6003932383438363531, a600313034393531343534, a600313037363135393033, a6003633313635363032, a6003636383638333737, a6003638353034353431, a6003932353237353830, a6003234343631343433, a6003833383235393736, a6003839393230323836, a6003930333435353231, a6003932363232363037, a6003339383039373732, a6003136393134373939, c80030383837313538313835373331, a600313031363834333131, a600313037353836323430, a600313037363535333933, c800726570726e77726b6c67766e61, a600313037363639393230, a6003737373437373130, a6003933373631343536, a6003934373031383634, a6003936343835393834, c80063753370696a66793776656f63, a6003937373933333735, a6003938353431393433, c80030363435343937343038303932, a6003839393033363632, c800656f7062637974307334737961, a600313034353339383138, a600313035313438333334, a600313036363736333932, a6003737323034373233, c80037363330303437313337393231, a6003930313132303431, a6003931303334393030, a6003931303931353239, a6003932313532393336, a6003938353837313830, a60034383734343133, a60033303636343037, c80030303034323238343833383832, c80030303630303735333133363536, a6003235343636323838, c80030373637353232393131353237, a60033393730323839, c80030303630323439383433313537, a6003139373034313232, c80074353930656271707332326f61, a6003533383737353936, a6003231373837363733, c80030383836343435343033353033, c80030383836343434303335303935
04/30 18:49:38 Debug: [music/search/performance] backend search of albums took 3369ms
04/30 18:49:50 Info: [stats] 3948mb Virtual, 1929mb Physical, 917mb Managed, 0 Handles, 72 Threads
04/30 18:50:00 Trace: [roonapi] [apiclient 192.168.1.203:55320] CONTINUE Changed {"message":"Core paired","is_error":false}
04/30 18:50:01 Trace: [metadatasvc] GOT [11690] (26027ms) Sooloos.Msg.Metadata.PerformerLitesData: a7003330343930, c900313239363034, 7a004d4e30303031393336333235, 7a004d4e30303031393336333235, 7a004d4e30303030383637343736, 7a004d4e30303030383637343736, 7a004d4e30303031313836313630, 7a004d4e30303031313836313630, a7003130353436333834, a70039383234313736, 7a004d4e30303032363634313535, 7a004d4e30303032363634313535, a70033373133383333, a70033373133383333, a70036303638323930, 7a004d4e30303030303832323830, a70033363336333537, c900313532393337, a70034373536343531, c90036323138, a70033393432373332, c900353830363535, a70033353035323637, c900313433393032, 7a004d4e30303031333434303131, 7a004d4e30303031333434303131, 7a004d4e30303030333133343430, 7a004d4e30303030333133343430, a70033393539363038, c90031303439303433, 7a004d4e30303031393132353031, 7a004d4e30303031393132353031, a70034323831323339, 7a004d4e30303030323539383031, a70033373131323932, c9003536363630, 7a004d4e30303031333639303436, 7a004d4e30303031383133323230, 7a004d4e30303031383133323230, 7a004d4e30303030333132313139, 7a004d4e30303030333132313139, 7a004d4e30303031393030313037, 7a004d4e30303031393030313037, 7a004d4e30303032313530383734, 7a004d4e30303032313530383734, 7a004d4e30303032333038363536, 7a004d4e30303032333038363536, 7a004d4e30303031333532383337, 7a004d4e30303031333532383337, a70038353430393635, 7a004d4e30303031343534313739, 7a004d4e30303031353437363937, 7a004d4e30303031353437363937, 7a004d4e30303031393136303839, a70037333235383733, 7a004d4e30303032343436333237, 7a004d4e30303033333634333338, 7a004d4e30303033333634333338, 7a004d4e30303033333834373535, 7a004d4e30303033333834373535, a7003130353034373836, a70033393634323630, a70033393634323630, a70035383031363730, a70038363339303532, a70038363339303532, a70039333232333439, a70039333232333439, a70039363935323833, a70039383735303637, 5e01aac16185c9ea7f4c8e68069ec341eb10, 5e01aac16185c9ea7f4c8e68069ec341eb10, 5e016ab6d19fa58dfa43bf8a0c990ecb72c9, 5e016ab6d19fa58dfa43bf8a0c990ecb72c9, a70036323435303031, 5e01278364c7a99cd6439045197e0c99d355, 5e01278364c7a99cd6439045197e0c99d355, 7a004d4e30303033313837303039, 7a004d4e30303033313837303039, 7a004d4e30303031303137363136, 7a004d4e30303031383835323039, 7a004d4e30303032333134393333, 7a004d4e30303032373830323838
04/30 18:50:01 Debug: [music/search/performance] backend search of composers took 26167ms
04/30 18:50:05 Info: [stats] 3950mb Virtual, 1932mb Physical, 919mb Managed, 0 Handles, 69 Threads
04/30 18:50:16 Trace: [metadatasvc] GOT [11688] (41223ms) Sooloos.Msg.Metadata.PerformerLitesData: a7003330343930, c900313239363034, a70033343136, c900323334343031, a70036303638323930, 7a004d4e30303030303832323830, a70037353432393836, c90032353339383637, a70036323435303031, 7a004d4e30303031393336333235, 7a004d4e30303031393336333235, 7a004d4e30303031313836313630, 7a004d4e30303031313836313630, a7003130353436333834, a70039383234313736, 7a004d4e30303032363634313535, 7a004d4e30303032363634313535, a70033373133383333, a70033373133383333, a70034373536343531, c90036323138, a70031393534, c9003236343932, a70033393432373332, c900353830363535, a70033363336333537, c900313532393337, a70036373732363530, c900373739353336, a70034373939363234, c900393037343933, a70034343331353533, c9003531333234, 7a004d4e30303030383637343736, 7a004d4e30303030383637343736, 5e01dbf6f1714da05d49aa810f3e0c9f02c4, 7a004d4e30303031373433383335, 7a004d4e30303031373433383335, a70033373131323932, c9003536363630, a7003136313936, c9003530363432, a70035383937333839, c900373332323639, a70035393935313532, 7a004d4e30303030333937343032, a70033353133383337, c9003632353337, 7a004d4e30303031333532383337, 7a004d4e30303031333532383337, 7a004d4e30303031333639303436, a70038353430393635, 7a004d4e30303031343534313739, 7a004d4e30303031353437363937, 7a004d4e30303031353437363937, 7a004d4e30303031383133323230, 7a004d4e30303031383133323230, 7a004d4e30303031393030313037, 7a004d4e30303031393030313037, 7a004d4e30303031393136303839, a70037333235383733, 7a004d4e30303032343436333237, 7a004d4e30303033333634333338, 7a004d4e30303033333634333338, 7a004d4e30303033333834373535, 7a004d4e30303033333834373535, a7003130353034373836, a70033393634323630, a70033393634323630, a70035383031363730, a70038363339303532, a70038363339303532, a70039333232333439, a70039333232333439, a70039363935323833, a70039383735303637, 5e01aac16185c9ea7f4c8e68069ec341eb10, 5e01aac16185c9ea7f4c8e68069ec341eb10, 5e016ab6d19fa58dfa43bf8a0c990ecb72c9, 5e016ab6d19fa58dfa43bf8a0c990ecb72c9, 5e01278364c7a99cd6439045197e0c99d355, 5e01278364c7a99cd6439045197e0c99d355, 7a004d4e30303033313837303039, 7a004d4e30303033313837303039, 7a004d4e30303031303137363136
04/30 18:50:16 Debug: [music/search/performance] backend search of performers took 41367ms
04/30 18:50:20 Info: [stats] 3954mb Virtual, 1935mb Physical, 924mb Managed, 0 Handles, 72 Threads
04/30 18:50:33 Trace: [roonapi] [apiclient 192.168.1.236:52432] CONTINUE Changed {"message":"Running (670)","is_error":false}
04/30 18:50:35 Warn: Error in web request https://metadataserver.roonlabs.net/md/4/search2/tracks?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50: NetworkError (Aborted.)
04/30 18:50:35 Warn: [metadatasvc] FAIL [11687] https://metadataserver.roonlabs.net/md/4/search2/tracks?uid=11457dfd-50c4-4002-8862-97908c412a59&lid=&token=511132e3-67fe-4318-8f4e-9ca87947f76a&query=Julian+Cope&collections[]=tidal-gb&collections[]=qobuz-gb&offset=0&count=50 (60002ms) NetworkError
04/30 18:50:35 Debug: [music/search/performance] backend search of tracks took 60131ms
04/30 18:50:35 Trace: [music/search] computing search results
04/30 18:50:35 Trace:         => performer LibraryPerformer[2313383, Julian Cope]
04/30 18:50:35 Trace:         => performer LibraryPerformer[2313383, Julian Cope]
04/30 18:50:35 Debug: [music/search/performance] ComputeTopResult took 2ms
04/30 18:50:35 Debug: [music/search/performance] end-to-end search took 60133ms
04/30 18:50:35 Info: [stats] 3961mb Virtual, 1942mb Physical, 930mb Managed, 0 Handles, 73 Threads

Just adding my experiences, although this could well be a different issue, I have noticed over the past few weeks (& I search tidal a lot) that searches are intermittently slow & on occasions appears to “freeze” completely. Closing the client application & reopening, then applying the same search criteria generally clears the problem but not always.
Search times (in logs) are pretty good and average around 45ms but the log also has many track load failures!

Hi,

@CrystalGipsy -I’m going to be asking the tech team about your traces at our next meeting, but can you let me know if the behavior is the same if you perform the search for Julian Cope a second time? Is there any change in then?

@PixelPopper - Can you please open a separate thread? Here I can see that the backend search took a bit of time in @CrystalGipsy’s logs but you might be experiencing something else if the average is around 45ms for you.

Thanks,
Noris

Topic - Searches Freeze or very slow created.

@noris Same search took about 3 secs this morning. Next time it’s slow will repeat the search.

1 Like

Just had another slow one, for Badly Drawn Boy, 2nd search was quicker.