Occasional long "freeze" between tracks

Hi
I have been experiencing an issue for some time that every once in a while in playback I will decide to move to the next track and Roon will take 20sec to more than a minute to start playing the next track. (as opposed to instantaneously in most cases)

  • I have been experiencing this on a RoonServer using i3 i5 and i7 computers. and also using a Rock
  • happens with various end points, including local output of the core itself
  • I noticed this happening mostly when using a “focused” filtered queue
  • I noticed this will never happen if I narrow down my library from over 50,000 tracks to about 10,000 tracks by removing the external storage connected to the core by USB 3.0 and only using the tracks stored on the internal hard drive
  • I notice in the log that whenever this happens there is some kind of refresh of the library/queue and something to do with “dirty tracks”

I’m attaching a log with an example of the issue:
20:08:20 Trace: [zone Lounge] Next
20:08:51 Info: [Lounge] [zoneplayer] Starting playback

Full log (sorry…) :

01/28 20:05:25 Trace: [library] finished with 116 dirty tracks 8 dirty albums 14 dirty performers 91 dirty works 116 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 215 changed objects
01/28 20:05:25 Trace: [dbperf] flush 0 bytes, 0 ops in 22 ms (cumulative 149501300 bytes, 133294 ops in 57436 ms)
01/28 20:05:25 Trace: [library] endmutation in 203ms
01/28 20:05:29 Trace: [Lounge] [zoneplayer/raat] sync Bluesound NODE 2i: realtime=79280422534999 rtt=500us offset=71450569534us delta=-134us drift=-21351us in 427.482s (-49.948ppm, -179.812ms/hr)
01/28 20:05:29 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:21/3:03] I Shall Be Released - Bob Dylan
01/28 20:05:29 Info: [stats] 22805mb Virtual, 1440mb Physical, 673mb Managed, 285 Handles, 95 Threads
01/28 20:05:34 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:26/3:03] I Shall Be Released - Bob Dylan
01/28 20:05:39 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:32/3:03] I Shall Be Released - Bob Dylan
01/28 20:05:44 Info: [stats] 22685mb Virtual, 1440mb Physical, 675mb Managed, 285 Handles, 76 Threads
01/28 20:05:44 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:37/3:03] I Shall Be Released - Bob Dylan
01/28 20:05:49 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:42/3:03] I Shall Be Released - Bob Dylan
01/28 20:05:54 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:47/3:03] I Shall Be Released - Bob Dylan
01/28 20:05:59 Trace: [Lounge] [zoneplayer/raat] sync Bluesound NODE 2i: realtime=79310956845899 rtt=500us offset=71450567845us delta=-26us drift=-23040us in 458.0175s (-50.306ppm, -181.101ms/hr)
01/28 20:05:59 Info: [stats] 22685mb Virtual, 1440mb Physical, 678mb Managed, 281 Handles, 78 Threads
01/28 20:05:59 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:52/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:04 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:57/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:09 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:02/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:14 Info: [stats] 22685mb Virtual, 1440mb Physical, 681mb Managed, 281 Handles, 78 Threads
01/28 20:06:14 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:07/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:19 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:12/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:24 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:17/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:29 Info: [stats] 22685mb Virtual, 1440mb Physical, 684mb Managed, 281 Handles, 77 Threads
01/28 20:06:29 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:22/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:30 Trace: [Lounge] [zoneplayer/raat] sync Bluesound NODE 2i: realtime=79341353714560 rtt=500us offset=71450566714us delta=329us drift=-24172us in 488.416s (-49.491ppm, -178.168ms/hr)
01/28 20:06:34 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:27/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:39 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:32/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:44 Info: [stats] 22685mb Virtual, 1440mb Physical, 686mb Managed, 281 Handles, 76 Threads
01/28 20:06:44 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:37/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:49 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:42/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:50 Info: [zoneplayer/signal] set crossfade bounds to startframes=22932 endframes=5475015 framecount=5452083
01/28 20:06:50 Info: [zoneplayer/signal] set crossfade bounds to backinglen=5678904 noisestart=520 noiseend=124150 total=128773
01/28 20:06:51 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/128 sec
01/28 20:06:54 Trace: [prebuffer] short read: 0 / 8820 fill=435125
01/28 20:06:54 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 2:47/3:03] I Shall Be Released - Bob Dylan
01/28 20:06:56 Info: [zone Lounge] OnPlayFeedback OnToNext
01/28 20:06:56 Debug: [zone Lounge] _Advance
01/28 20:06:56 Info: [library] recorded play for profile 6c53a0d2-eb7a-40c8-a4e8-c1bddbcbade9: mediaid=50:1:a496156a-5706-4d39-bc7b-51be76b74e1c metadataid=123:0:MT0046070052 contentid= libraryid=50:1:a496156a-5706-4d39-bc7b-51be76b74e1c isfromswim=True
01/28 20:06:56 Trace: [zone Lounge] queue got oversized. trimming 1 items from start
01/28 20:06:56 Debug: [zone Lounge] Remove(1 items, for_replace=False)
01/28 20:06:56 Info: after removeall ordered count 5000 shuffle count 5000
01/28 20:06:56 Trace: [library] finished with 116 dirty tracks 8 dirty albums 14 dirty performers 91 dirty works 116 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 215 changed objects
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Info: [zone Lounge] OnPlayFeedback Playing
01/28 20:06:57 Trace: [dbperf] flush 0 bytes, 0 ops in 23 ms (cumulative 149501300 bytes, 133294 ops in 57459 ms)
01/28 20:06:57 Trace: [library] endmutation in 354ms
01/28 20:06:57 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] SENT [540]{"request":"update_artwork","mimetype":"image/jpeg"}
01/28 20:06:57 Trace: [radio] [3] done in 46ms
01/28 20:06:57 Trace: [radio] [3] get next items nitems=1 flushed_trackids=
01/28 20:06:57 Trace: [radio] [3]     Track דודו פישר - Gott Fun Avraham
01/28 20:06:57 Trace: [radio] [3] done in 47ms
01/28 20:06:57 Info: ComputeSwimType(LimitToSeed=True, PerformerIds.Count=0, ComposerIds.Count=0)
01/28 20:06:57 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 149501300 bytes, 133294 ops in 57460 ms)
01/28 20:06:57 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] GOT [540] {"status":"Success"}
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:06:57 Info: [Lounge] [zoneplayer] Queueing: /roon/sys/storage/smbmounts/RoonStorage_8febdb0dbf1cf9eef114ff62eecee5adf67bc45c/rock-pop/The Who/The Who - 2013 Tommy3CDHQ/Disc 2 - CD/04 Pete Townshend Amazing Journey - Demo.flac
01/28 20:06:57 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Enhanced
Elements:
    Source Format=Wav 44100/16/2 BitRate=1411 Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-2.4080283210948212 Quality=Enhanced
    CrossFade Milliseconds=10000 Quality=Enhanced
    HeadroomGain GainDb=-3 Quality=Enhanced
    SampleRateConversion FromSampleRate=44100 ToSampleRate=176400 Algorithm=HighQuality Quality=Enhanced
    Convolution
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Raat Device=Bluesound NODE 2i
    Output OutputType=Local_Analog_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
01/28 20:06:57 Debug: GET /image/dunaaaaa.256.jpg
01/28 20:06:57 Debug: GET /image/dunaaaaa.256.jpg
01/28 20:06:57 Info: [library/albumdetails] Created album details screen for LibraryAlbum[861999, Various Artists - Francis Goya - Hits 2CD]
01/28 20:06:57 Info: [library/albumdetails] 
01/28 20:06:57 Debug: GET /image/dunaaaaa.jpg
01/28 20:06:57 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il
01/28 20:06:57 Debug: GET /image/dunaaaaa.256.jpg
01/28 20:06:57 Debug: GET /image/yxraaaaa.256.jpg
01/28 20:06:57 Info: [library/albumdetails] Created album details screen for LibraryAlbum[861999, Various Artists - Francis Goya - Hits 2CD]
01/28 20:06:57 Info: [library/albumdetails] 
01/28 20:06:57 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il
01/28 20:06:58 Info: [Lounge] [zoneplayer] Open result (Queueing): Result[Status=Success]
01/28 20:06:58 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il returned after 524 ms, status code: 200
01/28 20:06:58 Info: [library/albumdetails] found 0 streaming service alternates
01/28 20:06:58 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il returned after 485 ms, status code: 200
01/28 20:06:58 Info: [library/albumdetails] found 0 streaming service alternates
01/28 20:06:59 Info: [stats] 22701mb Virtual, 1460mb Physical, 724mb Managed, 286 Handles, 82 Threads
01/28 20:07:00 Trace: [Lounge] [zoneplayer/raat] sync Bluesound NODE 2i: realtime=79371867606765 rtt=500us offset=71450564606us delta=232us drift=-26280us in 518.9315s (-50.643ppm, -182.313ms/hr)
01/28 20:07:00 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:04/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:01 Info: [Lounge] [zoneplayer] Crossfade finished
01/28 20:07:05 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:09/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:10 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:14/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:14 Info: [stats] 22701mb Virtual, 1461mb Physical, 726mb Managed, 285 Handles, 82 Threads
01/28 20:07:15 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:19/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:20 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:24/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:25 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:29/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:29 Info: [stats] 22677mb Virtual, 1465mb Physical, 729mb Managed, 285 Handles, 76 Threads
01/28 20:07:30 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:34/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:31 Trace: [Lounge] [zoneplayer/raat] sync Bluesound NODE 2i: realtime=79402348132653 rtt=500us offset=71450563132us delta=-298us drift=-27754us in 549.4135s (-50.516ppm, -181.858ms/hr)
01/28 20:07:35 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:39/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:40 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:44/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:44 Info: [stats] 22677mb Virtual, 1466mb Physical, 664mb Managed, 285 Handles, 76 Threads
01/28 20:07:45 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:49/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:50 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:54/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:55 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 0:59/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:07:59 Info: [stats] 22693mb Virtual, 1466mb Physical, 668mb Managed, 285 Handles, 81 Threads
01/28 20:08:00 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:04/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:08:01 Trace: [Lounge] [zoneplayer/raat] sync Bluesound NODE 2i: realtime=79432898668090 rtt=500us offset=71450561668us delta=20us drift=-29218us in 579.966s (-50.380ppm, -181.368ms/hr)
01/28 20:08:05 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:09/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:08:10 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:14/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:08:14 Info: [stats] 22693mb Virtual, 1466mb Physical, 670mb Managed, 285 Handles, 81 Threads
01/28 20:08:15 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:19/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:08:19 Trace: [library] finished with 11977 dirty tracks 681 dirty albums 3070 dirty performers 2927 dirty works 3363 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 10042 changed objects
01/28 20:08:19 Debug: [query] Sooloos.Broker.Music.LibraryTrack: 11977 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=LibraryTrack)
01/28 20:08:20 Trace: [Lounge] [Enhanced 7.4x, 16/44 WAV => 24/176] [100% buf] [PLAYING @ 1:24/2:08] 13 Francis Goya Lights Of Moscow - 
01/28 20:08:20 Trace: [zone Lounge] Next
01/28 20:08:20 Info: [zone Lounge] OnPlayFeedback Stopped
01/28 20:08:20 Debug: [zone Lounge] _Advance
01/28 20:08:20 Trace: [zone Lounge] Selecting Source state=Stopped
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] SENT [625]{"request":"request_source"}
01/28 20:08:20 Trace: [zone Lounge] queue got oversized. trimming 1 items from start
01/28 20:08:20 Debug: [zone Lounge] Remove(1 items, for_replace=False)
01/28 20:08:20 Info: after removeall ordered count 5000 shuffle count 5000
01/28 20:08:20 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] GOT [625] {"status":"Success"}
01/28 20:08:20 Info: [audio/env] [zoneplayer] All streams were disposed
01/28 20:08:20 Trace: [Lounge] [zoneplayer/raat] Endpoint Bluesound NODE 2i State Changed: Playing => Prepared
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] SENT [626]{"request":"end_stream"}
01/28 20:08:20 Debug: [raat/tcpaudiosource] disconnecting
01/28 20:08:20 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] GOT [20] {"status":"Ended"}
01/28 20:08:20 Trace: [Lounge] [Enhanced, 16/44 WAV => 24/176] [100% buf] [STOPPED @ 0:00/4:46] Amazing Journey - Pete Townshend / The Who
01/28 20:08:20 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] GOT [626] {"status":"Success"}
01/28 20:08:20 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:20 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:20 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:20 Trace: [Lounge] [Enhanced, 16/44 WAV => 24/176] [100% buf] [LOADING @ 0:00] Amazing Journey - Pete Townshend / The Who
01/28 20:08:20 Trace: [musicpowerstate] music is playing, preventing idle sleep
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] SENT [629]{"request":"update_artwork","mimetype":"image/jpeg"}
01/28 20:08:20 Trace: [Bluesound NODE 2i @ 192.168.1.101:39347] [raatclient] GOT [629] {"status":"Success"}
01/28 20:08:21 Info: sleep 200ms after flush
01/28 20:08:21 Debug: GET /image/yxraaaaa.256.jpg
01/28 20:08:21 Debug: GET /image/yxraaaaa.256.jpg
01/28 20:08:21 Debug: GET /image/yxraaaaa.256.jpg
01/28 20:08:21 Debug: GET /image/yxraaaaa.1024.jpg
01/28 20:08:21 Debug: GET /image/ufjaaaaa.256.jpg
01/28 20:08:24 Debug: [query] Sooloos.Broker.Music.LibraryTrack: 11977 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=LibraryTrack)
01/28 20:08:28 Trace: [Lounge] [Enhanced, 16/44 WAV => 24/176] [100% buf] [LOADING @ 0:00] Amazing Journey - Pete Townshend / The Who
01/28 20:08:29 Trace: [library] endmutation in 10601ms
01/28 20:08:29 Info: [stats] 22662mb Virtual, 1445mb Physical, 739mb Managed, 282 Handles, 83 Threads
01/28 20:08:30 Trace: [library] finished with 11977 dirty tracks 681 dirty albums 3070 dirty performers 2927 dirty works 3363 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 10042 changed objects
01/28 20:08:30 Debug: [query] Sooloos.Broker.Music.LibraryTrack: 11977 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=LibraryTrack)
01/28 20:08:35 Debug: [query] Sooloos.Broker.Music.LibraryTrack: 11977 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=LibraryTrack)
01/28 20:08:40 Trace: [library] endmutation in 10339ms
01/28 20:08:40 Trace: [radio] [3] done in 161ms
01/28 20:08:40 Trace: [radio] [3] get next items nitems=1 flushed_trackids=
01/28 20:08:40 Trace: [radio] [3]     Track Phil Collins - Long Long Way to Go
01/28 20:08:40 Trace: [radio] [3] done in 162ms
01/28 20:08:40 Info: ComputeSwimType(LimitToSeed=True, PerformerIds.Count=0, ComposerIds.Count=0)
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 4 ms (cumulative 149501300 bytes, 133294 ops in 57464 ms)
01/28 20:08:40 Trace: [Lounge] [Enhanced, 16/44 WAV => 24/176] [100% buf] [LOADING @ 0:00] Amazing Journey - Pete Townshend / The Who
01/28 20:08:40 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:40 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:40 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:40 Debug: [query] Sooloos.Broker.Transport.TransportItem: 5015 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
01/28 20:08:40 Info: [library/albumdetails] Created album details screen for LibraryAlbum[1129519, The Who - Tommy]
01/28 20:08:40 Info: [library/albumdetails] 79004d5730303030333932353235, 79004d5230303031333930363434, 79004d5230303034303733373039, 79004d5230303033393934313538, 79004d5230303031373832393732, 79004d5230303031373637393736, 79004d5230303033393931383631, 79004d5230303030343835363233, 79004d5230303030303938353232, 79004d5230303034303335343238, 79004d5230303034303335343237, 79004d5230303034303438373034, 79004d5230303033373337373235, 79004d5230303033373331303034, 79004d5230303033303336313637, 79004d5230303032373732373133, 79004d5230303030393936313439, 79004d5230303030383836333632, 79004d5230303030363234313937, 79004d5230303034303335343236, 79004d5230303035303631333630, 79004d5230303035303634313737, 79004d5230303033393934313737, 79004d5230303033393934313535, 79004d5230303033393538383732, 79004d5230303031373737373534, 79004d5230303031333930333530, 79004d5230303030343938303333, 79004d5230303030343935333731, 79004d5230303030343837383936, 3e0185df6acc081ffe49b07422621cffc37a, 3e01c9b6b3749a7dd132b23fcabf60471c52, 3e0105caeafc10a2924f9e881e8b44ec8e37, 3e01aef93381cc66b13e85b1be3196edcfb6, 3e01a231297424b07c37a3c95eb6bfdda480, 3e011c391f301c232c36aaea142de5b18c85, 3e01d593ea25feef7639bc86f86778d7228f, 3e01cfa47f7de30c024f9485852c568a9f17, 79004d5230303034343535383632, 79004d5230303034383837333632, 79004d5230303034333134363736, 79004d5230303034323331393434, 79004d5230303034323331393036, 79004d5230303034323331393033, 79004d5230303034323031363336, 79004d5230303033353937313930, 79004d5230303033343939353835, 79004d5230303031373730383630, 79004d5230303031373639363230, 79004d5230303031333930313833, 79004d5230303030353539373631, 3e015aa94efb6f4c4d408613effbe9eb5aec, 3e011302e5df0f714c4b83d3fa18eb71587a, 3e01aabb8eb892f6eb4cb02dbfcf8a464ca7, 3e01ac93dd8b9ced914bb86cc22ca4c4b250, 3e01e6b9968059f0044087e2c3a981ca5f32, 3e01a3e7981bad582a4182e1685fe356ff10, 3e01cbc1c492be991f4b946245708c3f4fa7, 3e018b2ad996108feb40b9f93208accc98db, 3e017c748444b409da31a212483b8f638c17, 3e01d3c0474139798434bde6b6fbefd7bc3b, 3e01036913b552c2f14db1157d4afecb2272, 3e012a980404914bbe4b81fbac6bb5b596a1, 3e016f23c107dbdddd4586fc979e71bf4724, 3e01e14107e145f91b408a7832a73baf6505, 3e010554f07f88915e48b9891906fa93091a, 3e014e81a1e1809dea4aabdd793d8498c2cc, 3e01e84a5d54da52cb43af5b50721267f1d8, 3e01ff77b32037edcc4bbfe7e8fe247064a9, 3e01d6a181b3b8a51f3889c92cb7f915d1f2, 3e01ce426bcbd8ce8e4a9f9a5762e5fe1754, 3e01ce73052d173c0e4f94c57ee2b833fc3e, 3e014b6e170de96aaf4193e10f96609fd13b, 3e01cc64a9eb76840e408cb165fc62a69c94, 3e01805abaea6fa37a42b68b580fdfde8b53, 3e01f47562b479d36e48810830058c5368cd, 3e01e354e07d99c5c240a2fd860b532f663d, 3e019e3429ee6a251b44aedbe659721f9def, 3e01539fece126b1204d9421f96f286f3b8b, 3e01196a08951f58ad4b8371ea8466aa4dbf, a600313038333130363634, a600313238343237333830, a600313137323636323437, a600313038333830363132, a600363336323131, a6003933313634313332, c800706867776e66723263327a6a63, c80079353074343974377069366e63, c80030303733313435333130343332, c80030303630323533373437343134, c80030303630323533373437343037, c8006e683163696a636436767a7862, c80030303630323439383237323336, c80030303630323533373437343132, c80030303630323533373437343036, c80079627a62656278733172716c62, c80030303630323533373934373738
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 149501300 bytes, 133294 ops in 57465 ms)
01/28 20:08:40 Trace: [library] finished with 4 dirty performers 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 4 changed objects
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 15 ms (cumulative 149501300 bytes, 133294 ops in 57480 ms)
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 149501300 bytes, 133294 ops in 57483 ms)
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 149501300 bytes, 133294 ops in 57485 ms)
01/28 20:08:40 Info: [library/albumdetails] Created album details screen for LibraryAlbum[1129519, The Who - Tommy]
01/28 20:08:40 Info: [library/albumdetails] 79004d5730303030333932353235, 79004d5230303031333930363434, 79004d5230303034303733373039, 79004d5230303033393934313538, 79004d5230303031373832393732, 79004d5230303031373637393736, 79004d5230303033393931383631, 79004d5230303030343835363233, 79004d5230303030303938353232, 79004d5230303034303335343238, 79004d5230303034303335343237, 79004d5230303034303438373034, 79004d5230303033373337373235, 79004d5230303033373331303034, 79004d5230303033303336313637, 79004d5230303032373732373133, 79004d5230303030393936313439, 79004d5230303030383836333632, 79004d5230303030363234313937, 79004d5230303034303335343236, 79004d5230303035303631333630, 79004d5230303035303634313737, 79004d5230303033393934313737, 79004d5230303033393934313535, 79004d5230303033393538383732, 79004d5230303031373737373534, 79004d5230303031333930333530, 79004d5230303030343938303333, 79004d5230303030343935333731, 79004d5230303030343837383936, 3e0185df6acc081ffe49b07422621cffc37a, 3e01c9b6b3749a7dd132b23fcabf60471c52, 3e0105caeafc10a2924f9e881e8b44ec8e37, 3e01aef93381cc66b13e85b1be3196edcfb6, 3e01a231297424b07c37a3c95eb6bfdda480, 3e011c391f301c232c36aaea142de5b18c85, 3e01d593ea25feef7639bc86f86778d7228f, 3e01cfa47f7de30c024f9485852c568a9f17, 79004d5230303034343535383632, 79004d5230303034383837333632, 79004d5230303034333134363736, 79004d5230303034323331393434, 79004d5230303034323331393036, 79004d5230303034323331393033, 79004d5230303034323031363336, 79004d5230303033353937313930, 79004d5230303033343939353835, 79004d5230303031373730383630, 79004d5230303031373639363230, 79004d5230303031333930313833, 79004d5230303030353539373631, 3e015aa94efb6f4c4d408613effbe9eb5aec, 3e011302e5df0f714c4b83d3fa18eb71587a, 3e01aabb8eb892f6eb4cb02dbfcf8a464ca7, 3e01ac93dd8b9ced914bb86cc22ca4c4b250, 3e01e6b9968059f0044087e2c3a981ca5f32, 3e01a3e7981bad582a4182e1685fe356ff10, 3e01cbc1c492be991f4b946245708c3f4fa7, 3e018b2ad996108feb40b9f93208accc98db, 3e017c748444b409da31a212483b8f638c17, 3e01d3c0474139798434bde6b6fbefd7bc3b, 3e01036913b552c2f14db1157d4afecb2272, 3e012a980404914bbe4b81fbac6bb5b596a1, 3e016f23c107dbdddd4586fc979e71bf4724, 3e01e14107e145f91b408a7832a73baf6505, 3e010554f07f88915e48b9891906fa93091a, 3e014e81a1e1809dea4aabdd793d8498c2cc, 3e01e84a5d54da52cb43af5b50721267f1d8, 3e01ff77b32037edcc4bbfe7e8fe247064a9, 3e01d6a181b3b8a51f3889c92cb7f915d1f2, 3e01ce426bcbd8ce8e4a9f9a5762e5fe1754, 3e01ce73052d173c0e4f94c57ee2b833fc3e, 3e014b6e170de96aaf4193e10f96609fd13b, 3e01cc64a9eb76840e408cb165fc62a69c94, 3e01805abaea6fa37a42b68b580fdfde8b53, 3e01f47562b479d36e48810830058c5368cd, 3e01e354e07d99c5c240a2fd860b532f663d, 3e019e3429ee6a251b44aedbe659721f9def, 3e01539fece126b1204d9421f96f286f3b8b, 3e01196a08951f58ad4b8371ea8466aa4dbf, a600313038333130363634, a600313238343237333830, a600313137323636323437, a600313038333830363132, a600363336323131, a6003933313634313332, c800706867776e66723263327a6a63, c80079353074343974377069366e63, c80030303733313435333130343332, c80030303630323533373437343134, c80030303630323533373437343037, c8006e683163696a636436767a7862, c80030303630323439383237323336, c80030303630323533373437343132, c80030303630323533373437343036, c80079627a62656278733172716c62, c80030303630323533373934373738
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 14 ms (cumulative 149501300 bytes, 133294 ops in 57499 ms)
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 149501300 bytes, 133294 ops in 57502 ms)
01/28 20:08:40 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 149501300 bytes, 133294 ops in 57504 ms)
01/28 20:08:40 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il
01/28 20:08:40 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il
01/28 20:08:40 Info: [library] recorded play for profile 6c53a0d2-eb7a-40c8-a4e8-c1bddbcbade9: mediaid=50:1:00a238b8-a7a3-4b98-8d18-d0c99bf58188 metadataid= contentid= libraryid=50:1:00a238b8-a7a3-4b98-8d18-d0c99bf58188 isfromswim=True
01/28 20:08:40 Debug: GET /image/wxraaaaa.256.jpg
01/28 20:08:41 Debug: GET /image/wxraaaaa.256.jpg
01/28 20:08:41 Trace: [library] finished with 11977 dirty tracks 681 dirty albums 3070 dirty performers 2927 dirty works 3363 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 10042 changed objects
01/28 20:08:41 Debug: [query] Sooloos.Broker.Music.LibraryTrack: 11977 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=LibraryTrack)
01/28 20:08:44 Info: [stats] 22654mb Virtual, 1429mb Physical, 500mb Managed, 282 Handles, 79 Threads
01/28 20:08:46 Debug: [query] Sooloos.Broker.Music.LibraryTrack: 11977 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=LibraryTrack)
01/28 20:08:51 Trace: [library] endmutation in 10789ms
01/28 20:08:51 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/works/translate?c=tidal-il
01/28 20:08:51 Debug: [easyhttp] POST to https://metadata.roonlabs.net/1/works/translate?c=tidal-il
01/28 20:08:51 Info: [Lounge] [zoneplayer] Playing: /roon/sys/storage/smbmounts/RoonStorage_8febdb0dbf1cf9eef114ff62eecee5adf67bc45c/rock-pop/The Who/The Who - 2013 Tommy3CDHQ/Disc 2 - CD/04 Pete Townshend Amazing Journey - Demo.flac
01/28 20:08:51 Trace: [Lounge] [Enhanced, 16/44 WAV => 24/176] [100% buf] [LOADING @ 0:00] Amazing Journey - Pete Townshend / The Who
01/28 20:08:51 Info: [Lounge] [zoneplayer] Queueing: /roon/sys/storage/smbmounts/RoonStorage_8febdb0dbf1cf9eef114ff62eecee5adf67bc45c/rock-pop/Bing Crosby/The All-Time Best of Bing Crosby/01 - Swinging On A Star.flac
01/28 20:08:51 Info: [Lounge] [zoneplayer]     Open Result (Playing):Result[Status=Success]
01/28 20:08:51 Info: [Lounge] [zoneplayer] Starting playback

The queue is limited to 5000 entries/tracks. Do you really need that many or do you just never clean up the queue?

A previous, never resolved and similar sounding issue was:

Hi @guy_gichon,

I cleaned up your log traces a bit, for future reference, you can use three to start a code block and another three to end a code block (like I have it edited for you now).

As for the issue itself, I have a few suggestions:

  1. It looks like you are playing to your Node 2i zone, can you share a snippet of you reproducing this behavior on “System Output”?

  2. Is the behavior the same with upsampling turned off?

  3. Does this behavior only occur with tracks stored on your USB drive? If you keep the USB drive connected but only access tracks in your internal storage, does the issue reproduce? You can focus on tracks on a specific storage type in Tracks -> Focus -> Inspector -> Storage Locations:

  4. It looks like you have quite a few items in the queue, if you perform a Clear Upcoming -> Clear All in the queue screen, does that help?

Hi
Thanks for trying to figure this out

  1. I can try later on but the logs look the same as far as I could understand them.
  2. Yes it happens also with no DSP
  3. It happens also with internal storage only. One of the servers I tried has the entire library in local drives, no external usb drive
  4. I use a focused queue based on tags which may produce a large amount of tracks and then I just click shuffle. I never paid attention to the items in the queue itself. I am trying now the same thing but clicked “clear all” before applying the focused filter. Let’s see how it works out.

Still happens even after clearing the queue and starting over fresh with a new “focused” filter and hitting shuffle.
After a few tracks I click “next” and it will happen.
Log looks the same as attached in original post.

There is an action called “rebuilding g query instead of re-sorting item by item” which looks to me like the culprit that is taking a long time to be completed

Hi @guy_gichon

If you don’t use this kind of queue and instead queue up ~100 tracks (e.g. from a playlist), do you still experience this behavior?

It doesn’t happen with a small queue. It doesn’t happen even with a queue of a few thousands

Hi @guy_gichon,

How many tracks exactly are being added to your queue when you shuffle this “focused” playlist?

Can you provide the steps that you are using to get this focused playlist going? Perhaps share a short video of how you initially start the queue?

It can be around 30,000 Nothing special about the focus (I think) - it could be as simple as selecting “favorites”

Attached a screen shot of a focused list on tracks I have not banned and have not yet tagged with 5 stars and didn’t play yet. I like to use this to listen to tracks I have not listened to and didn’t rate

I was thinking I should probably give a more elaborate scenario. Hope it won’t come out to long

So,
My library is 50,000k tracks. Since Roon doesn’t support user track rating from tags I found the need to go over my entire library and classify the tracks I like and the ones I like less. For this I use the “favorite” and “banned” options. In addition I added a manual tag of 5 stars. I “ban” any track I don’t like and “favorite” the ones I do. I add “5 star” tag to the ones I really really like.
For the rating process I created a book mark of a focused queue:

  1. No banned tracks
  2. No 5 star tag
  3. Not played - this is a workaround since there is no option to filter out “favorite” tracks.

This focused filter currently results about 40,000 tracks.
Once the filter is set I click shuffle and start listening and classifying the tracks - favorite, ban, 5 stars.

When I ban a track I will also skip to the next track. This is when I experience the “freeze”

Hi @guy_gichon,

Thanks for the additional information. Can you please share a screenshot of what your queue screen looks like after you have queued up the albums based on your filter?

Sure
Here it is

This topic was automatically closed 36 hours after the last reply. New replies are no longer allowed.

Hi @guy_gichon,

Thanks for sharing that screenshot. It looks like this thread auto-closed so I have re-opened it for you.

I spoke to the technical team regrading your report and we will attempt to reproduce this behavior in the lab.

One other thing I wanted to mention:

Roon does support album tag and track tag: https://kb.roonlabs.com/Tags#ROONALBUMTAG_amp_ROONTRACKTAG

If you wish, you can also use tags to create a rating system based on album or track tags.

Thanks

I was not aware of the new tag support in 1.7.
It will be useful for me. Thanks!

Hi
Any update from the technical team?

Hi @guy_gichon,

I took a look at our internal tracker today, and I can see that your ticket is still in our review queue. This means our team is still planning to look at this, but it has not reached QA’s queue yet.

Once the ticket has been reviewed by the QA team I will let you know. Thanks in advance for your patience!

Hi @guy_gichon,

I appreciate your patience until this reached QA’s review queue. They have started reviewing your case and have inquired if you could please let us know if you also experience this behavior when performing the “focus filtered” queue on TIDAL/Qobuz tracks only? Do let me know when possible so I can attach this info to your case notes, thanks!

Hi and thanks for the support
It doesn’t happen when focusing on Tidal.
Please note that a focus on Tidal results with a queue of about 3000 tracks. I have noticed that with a queue of less than 10k, with or without tidal, the issue does not occur.

Thanks.