Slow Search Issues

Support
43

ISP supplied router which is set up in modem mode, it’s aVirgin Media Hub 3, no idea of the model but I think it’s made by Netgear. This feeds my Ubiquiti router but I can’t connect to this without taking out all the network to rest of house and wireless aps as this only has one port out which feeds a Unify switch which then feeds the rooms and wireless aps. Then In each room I have Cisco switches.

The thing is that for the majority of time I have had search issues there has been nothing wrong with my internet connection as I have checked the logs on the modem to see. It does however correlate with try DNS resolutions you are seeing in Roons logs. I have asked my ISP about the issue but I doubt I will get anywhere fast as they are pretty useless at support, but I have no choice but to use them as no other service provider for fibre on my street.

I’ve got another switch I might try that core is connected to and see if that changes anything.

44

23:45 very slow search looking for Heaven or Las Vegas by Cocteau Twins. Internet fine no loss of connection or T3 timeout. .I just changed over my switch before this session so core will show loss of network before the start of Soothing by Laura Marling from Sempa Femina. Searching for the track I was playing was pretty fast but during playing I did the above search and then the slowness.

45

Powered off all network kit late last night including modem, then turned on each one in sequence to see if this helps. But I don’t think this slowness is to do with my network given their are a lot of other users having similar issues. Also no t3 timeouts for over 24 hours and still get slowness.

46

Hi @CrystalGipsy,

Thanks for checking the modem again for T3 timeouts. Since this is still an ongoing investigation, we want to get a clear baseline of results here. Can you please run the test I previously mentioned and let me know your results, so that I can submit it as a data point in the investigation?

Thanks,
Noris

47

When it happens again I will.

1 Like
48

@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.

49

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

50

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?

51

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

52

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
53

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
54

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,

55

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

56

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

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

57

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

58

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
59

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
60

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

61

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
62

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!

image
E9D3FE0C-C829-4939-8C94-CB9F23F219E7.jpeg2224×1148 1.86 MB