Long gaps between tracks since build 1169

@benjamin what will todays update bring, regarding this issue?

Nothing in this release. But, if you were interested in CarPlay with ARC you did get that… :unamused:

2 Likes

Thanks @Vincent_Kennedy :+1:

2 Likes

@support can you please confirm this is a known issue being actively worked in? The lack of updates and responses is less than ideal.

Can @support comment on why the Core works 100% fine with ARC but has these massive issues with increasing delays between tracks the longer it goes between Core reboots on any other endpoint. On the same network - even on the same phone for ARC - plays fine - switch to Roon app - same songs cued up - massive delays. Gets to up to 30-40 seconds after a few days.

Been going on forever - spent so many hours chasing rainbows with tech support - please at least give a status update - or confirm the known issue. The silence is a killer

3 Likes

Hi James

Sorry you’re affected too.

Support did more than once acknowledge this issue, in this thread and others. And they’re working on it, that they told us too. Let’s hope they fix it asap.

Arc is different as streaming services go direct to the arc device and do not use the cores cache which seems to the area of issue for local playback from what I have gathered from support this far. As it’s also not affecting everyone or it doesn’t manifest itself in same way in some users setups it’s hard to get full data points as to what the underlying issue is. Support are aware and it’s being worked on we will likely only hear when they have a solution.

Thanks for the note - appreciate hearing support is aware and hopefully working on it. Thank you for letting me know.

In my case with ARC this is with local files - not songs through Tidal - local files stored on external HD connected directly to the Nucleus. Arc plays everything without issue. Regular Roon app and any end point (even the Nucleus directly with HDMI with the same local files from the direct external drive - so no network in play at all) - delays between tracks increases over time.

This seems different to what this thread is about then as it’s mostly affected streaming along with gapless play on streaming. This is what they are investigating. Don’t know any that have had issues with local files. Although if the cache if problematic it could affect all playback.

There’s this thread

I’d guess not the same issue

Maybe the latest early access release (build 1197) will improve the issue with long gaps between tracks?

@Dennis_Mutsaers The first result is in, looks promising …

Looks like the issue is not totally under control yet, given this post

I played The Dark Side of the Moon from my library via Roon, which played gaplessly. I then played The Dark Side of the Moon from Qobuz via Roon, and there was a 2 second gap between tracks 1 and 2. I then played The Dark Side of the Moon direct from Qobuz on my Mac, which played gaplessly. I then went back to Roon and now DSOTM is gapless again. There is definitely a problem with the interface between Roon and Qobuz and Build 1193.

1 Like

I’ve actually been noticing this only when the album starts. It takes 20 seconds for the first track to start. After that it’s fine. It happens for PCM and DSD formats.

And this is rubbing only on the local library. ROCK/NUC Core, wired connection.

Having similar issues.

Attached Roon log for what is happening in the background when there’s a long lag in loading a song.
Happens with local or Tidal.

/26 18:11:37 Info: [library/albumdetails] 3e01f7495d927f916d46a21aab10b7ee0ccb, 3e0106c9c20371fd2346988972ec329d1d7b, 3e01ff32f605d94ffc4ab289de6c68aabf9b, a600313038383537373038, c8006f697676656263676c697a7962, cd003870586241413463766d5969505844435537

01/26 18:11:37 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 202346728 bytes, 197395 ops in 738428 ms)

01/26 18:11:37 Trace: GetImageData[Remote](id=35949 spec=4by3_2048 key=rebcaaaa) => got from cache overalltime=0ms

01/26 18:11:37 Trace: GetImageData[Remote](id=35949 spec=4by3_2048 key=rebcaaaa) => got from cache overalltime=2ms

01/26 18:11:37 Trace: [roondns] flushed 33 last-known-good entries

01/26 18:11:37 Info: [library/albumdetails] found 1 ingroup alternates, 0 other alternates

01/26 18:11:37 Info: [library/albumdetails] Created album details screen for LibraryAlbum[1808175, TWICE - MOONLIGHT SUNRISE]

01/26 18:11:37 Info: [library/albumdetails] 3e01666db9533017b446917a8accfe3fe55a, 3e01cb3a34d964f3784c949142a9ad1b2549, 3e018527134f17eb074798760a69f096f240, a600323731393634393836, a600323732323537323831, a600323731323039393738, a600323730363934393238, c80062626970696a716f327a366562, c8007277666d68677669316e726663, cd00346f3846395a71425667595f5a3530493359

01/26 18:11:37 Info: library album LibraryAlbum[1808175, TWICE - MOONLIGHT SUNRISE]

01/26 18:11:37 Info: retu album LibraryAlbum[88111, TWICE - Story Begins]

01/26 18:11:37 Info: retu album LibraryAlbum[85807, TWICE - Page Two]

01/26 18:11:37 Info: retu album LibraryAlbum[90415, TWICE - Twicecoaster: Lane 1]

01/26 18:11:37 Info: retu album LibraryAlbum[89647, TWICE - Twicecoaster: Lane 2]

01/26 18:11:37 Info: retu album LibraryAlbum[86575, TWICE - Signal]

01/26 18:11:37 Info: retu album LibraryAlbum[85295, TWICE - One More Time]

01/26 18:11:37 Info: retu album LibraryAlbum[94255, TWICE - Twicetagram]

01/26 18:11:37 Info: retu album LibraryAlbum[84783, TWICE - Merry & Happy]

01/26 18:11:37 Info: retu album LibraryAlbum[83759, TWICE - Candy Pop]

01/26 18:11:37 Info: retu album LibraryAlbum[91695, TWICE - What Is Love?]

01/26 18:11:37 Info: retu album LibraryAlbum[90927, TWICE - Wake Me Up]

01/26 18:11:37 Info: retu album LibraryAlbum[87343, TWICE - Summer Nights]

01/26 18:11:37 Info: retu album LibraryAlbum[81711, TWICE - BDZ]

01/26 18:11:37 Info: retu album LibraryAlbum[82479, TWICE - BDZ]

01/26 18:11:37 Info: retu album LibraryAlbum[92463, TWICE - YES or YES]

01/26 18:11:37 Info: retu album LibraryAlbum[88879, TWICE - The Year of "YES"]

01/26 18:11:37 Info: retu album LibraryAlbum[93231, TWICE - Fancy You]

01/26 18:11:37 Info: retu album LibraryAlbum[97583, TWICE - MORE & MORE]

01/26 18:11:37 Info: retu album LibraryAlbum[1585711, TWICE - More & More]

01/26 18:11:37 Info: retu album LibraryAlbum[84271, TWICE - Eyes Wide Open]

01/26 18:11:37 Info: retu album LibraryAlbum[83247, TWICE - Better]

01/26 18:11:37 Info: retu album LibraryAlbum[94767, TWICE - Cry for Me]

01/26 18:11:37 Info: retu album LibraryAlbum[353327, TWICE - Kura Kura]

01/26 18:11:37 Info: retu album LibraryAlbum[386095, TWICE - Taste of Love]

01/26 18:11:37 Info: retu album LibraryAlbum[1089839, TWICE - The Feels]

01/26 18:11:37 Info: retu album LibraryAlbum[1116975, TWICE - Formula of Love: O+T=<3]

01/26 18:11:37 Info: retu album LibraryAlbum[1145647, TWICE - Doughnut]

01/26 18:11:37 Info: retu album LibraryAlbum[1150255, TWICE - Doughnut]

01/26 18:11:37 Info: retu album LibraryAlbum[1554991, TWICE - Celebrate]

01/26 18:11:37 Info: retu album LibraryAlbum[1586991, TWICE - Between 1&2]

01/26 18:11:37 Trace: [music/query] performing album query

01/26 18:11:37 Trace: [music/query] query returned 14 Sooloos.Broker.Api.AlbumLite(s)

01/26 18:11:37 Trace: [music/query] performing album query

01/26 18:11:37 Trace: [music/query] query returned 17 Sooloos.Broker.Api.AlbumLite(s)

01/26 18:11:37 Trace: GetImageData[Remote](id=35948 spec=1by1_512 key=qebcaaaa uri=https://imagecache.roonlabs.net/im/1/artists/5e0108586bbae33baf4282968f4e826b2b64/avatar/1by1_512.jpg) => fetched in 314ms status=304 size=0 overalltime=325ms

01/26 18:11:37 Debug: [easyhttp] [43149] POST to https://discovery.roonlabs.net/1/query returned after 906 ms, status code: 200, request body size: 74 B

01/26 18:11:38 Debug: [easyhttp] [43150] GET to https://messaging.roonlabs.net/1/api/messages/67664fa6-d512-4486-8c1b-8c318ecd0fd0 returned after 874 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43151] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 855 ms, status code: 200, request body size: 312 B

01/26 18:11:38 Info: [library/albumdetails] found 1 streaming service alternates

01/26 18:11:38 Debug: [easyhttp] [43152] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-au returned after 863 ms, status code: 200, request body size: 71 B

01/26 18:11:38 Debug: [easyhttp] [43153] GET to https://metadatatext.roonlabs.net/1/blobs?objectId=62:1:925d49f7-917f-466d-a21a-ab10b7ee0ccb&type=review&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-au returned after 863 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43154] GET to https://metadatatext.roonlabs.net/1/blobs?objectId=191:0:598204&type=biography&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-au returned after 896 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43156] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 845 ms, status code: 200, request body size: 380 B

01/26 18:11:38 Info: [library/albumdetails] found 2 streaming service alternates

01/26 18:11:38 Debug: [easyhttp] [43157] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-au returned after 861 ms, status code: 200, request body size: 148 B

01/26 18:11:38 Debug: [easyhttp] [43164] GET to https://metadatatext.roonlabs.net/1/blobs?objectId=62:1:53b96d66-1730-46b4-917a-8accfe3fe55a&type=review&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-au returned after 857 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43160] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/albums/morefromcollaborators?profileId=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 868 ms, status code: 404

01/26 18:11:38 Debug: [easyhttp] [43165] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/recommended/albums?profileId=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 896 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43159] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/timeline/albums?profileId=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 911 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43161] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/similar/albums?profileId=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 986 ms, status code: 404

01/26 18:11:38 Debug: [easyhttp] [43162] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/performers/similar?profileId=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 1125 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 202346728 bytes, 197395 ops in 738429 ms)

01/26 18:11:38 Debug: [easyhttp] [43158] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/albums/new?profile=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 1235 ms, status code: 200, request body size: 0 B

01/26 18:11:38 Debug: [easyhttp] [43163] GET to https://discover.roonlabs.net/1/albums/62:1:53b96d66-1730-46b4-917a-8accfe3fe55a/albums/morefromgenre?profileId=b575db24-1ef0-4f48-9f2f-584db9bf194c&c=tidal-au returned after 1385 ms, status code: 404

01/26 18:11:39 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [PLAYING @ 0:47/5:35] 迷霧 - A-Lin

01/26 18:11:42 Info: [library] saved recent ProfileId=b575db24-1ef0-4f48-9f2f-584db9bf194c Time=1/26/2023 7:11:41 AM DataType=album Type=play MetadataId=50828862 ContentId=1808175 LibraryId=1808175 Text= Genre=

01/26 18:11:42 Info: =======================================================================

01/26 18:11:42 Trace: Track MOONLIGHT SUNRISE (trackid=MOONLIGHT SUNRISE,albumid=50:1:29d2041a-efd5-4c4e-b1ec-6d01d1f62fe0)

01/26 18:11:42 Trace: Performance LibraryPerformance[27938421, LibraryWork[7358324, MOONLIGHT SUNRISE, by LibraryPerformer[42783281, Lee Woo Hyun], LibraryPerformer[128350, earattack]]]

01/26 18:11:42 Info: Track MOONLIGHT SUNRISE (trackid=MOONLIGHT SUNRISE,albumid=50:1:29d2041a-efd5-4c4e-b1ec-6d01d1f62fe0)

01/26 18:11:42 Info: Performance LibraryPerformance[27938421, LibraryWork[7358324, MOONLIGHT SUNRISE, by LibraryPerformer[42783281, Lee Woo Hyun], LibraryPerformer[128350, earattack]]]

01/26 18:11:42 Trace: [zone Tambaqui] Playing 1 Items

01/26 18:11:42 Info:

--[ SignalPath ]---------------------------------------------

SignalPath Quality = Enhanced

Elements:

Source Format=Flac 44100/16/2 BitRate=653 Quality=Lossless

UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless

ParametricEQ

Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless

Raat Device=Mola-Mola Tambaqui

Output OutputType=Local_Analog Quality=Lossless SubType= Model=

------------------------------------------------------------

01/26 18:11:42 Info: [zone Tambaqui] OnPlayFeedback Stopped

01/26 18:11:42 Info: [zone Tambaqui] Canceling Pending Sleep

01/26 18:11:42 Trace: [zone Tambaqui] Playing TransportItem

01/26 18:11:42 Info: [zone Tambaqui] Canceling Pending Sleep

01/26 18:11:42 Trace: [Tambaqui] [zoneplayer/raat] Endpoint Mola-Mola Tambaqui State Changed: Playing => Prepared

01/26 18:11:42 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] SENT [110]{"request":"end_stream"}

01/26 18:11:42 Debug: [raat/tcpaudiosource] disconnecting

01/26 18:11:42 Info: [library] recorded play for profile b575db24-1ef0-4f48-9f2f-584db9bf194c: mediaid=50:1:de19eb0a-7b73-41d1-802a-8e993350ba4c metadataid=97:0:252830329383187 contentid= libraryid=50:1:de19eb0a-7b73-41d1-802a-8e993350ba4c isfromradio=False isfrommix=False isfrommobile=

01/26 18:11:42 Trace: [zone Tambaqui] Selecting Source state=Stopped

01/26 18:11:42 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] SENT [111]{"request":"request_source"}

01/26 18:11:42 Trace: [zone Tambaqui] queue got oversized. trimming 1 items from start

01/26 18:11:42 Debug: [zone Tambaqui] Remove(1 items, for_replace=False)

01/26 18:11:42 Info: [audio/env] [zoneplayer -> stream] All streams were disposed

01/26 18:11:42 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [77] {"status":"Ended"}

01/26 18:11:42 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [110] {"status":"Success"}

01/26 18:11:42 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [111] {"status":"Success"}

01/26 18:11:42 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [5] {"controls":{"volume":{"type":"db","min":-80.0,"info":{"config":{"volume_step":1.0,"min_volume":-80,"max_volume":0}},"max":0.0,"mute":false,"value":-12,"step":1.0},"source":{"value":"selected","info":{"config":{"standby":false}}}},"status":"Success","action":"Changed"}

01/26 18:11:42 Info: after removeall ordered count 8000 shuffle count 8000

01/26 18:11:42 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed

01/26 18:11:42 Trace: [library] finished with 124 dirty tracks 7 dirty albums 50 dirty performers 41 dirty works 41 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 140 changed objects

01/26 18:11:42 Info: [library/albumdetails] found 1 ingroup alternates, 1 other alternates

01/26 18:11:42 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [STOPPED @ 0:00] MOONLIGHT SUNRISE - TWICE

01/26 18:11:42 Debug: [query] Sooloos.Broker.Transport.TransportItem: 8001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)

01/26 18:11:42 Debug: [query] Sooloos.Broker.Transport.TransportItem: 8001 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)

01/26 18:11:42 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [LOADING @ 0:00] MOONLIGHT SUNRISE - TWICE

01/26 18:11:42 Trace: [roonapi] [apiclient 192.168.2.4:42374] CONTINUE Changed {"zones_changed":[{"zone_id":"16015224073fb032eb45b23644eb8f5a209d","display_name":"Tambaqui","outputs":[{"output_id":"17015224073fb032eb45b23644eb8f5a209d","zone_id":"16015224073fb032eb45b23644eb8f5a209d","can_group_with_output_ids":["1701db8f072065fefe3359ce2c1d626a6569","17015224073fb032eb45b23644eb8f5a209d","170181b110a21e60345ab368c8f14f30e36e"],"display_name":"Tambaqui","volume":{"type":"db","min":-80,"max":0,"value":-12,"step":1,"is_muted":false,"hard_limit_min":-80,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"Mola-Mola Tambaqui","supports_standby":false,"status":"selected"}]}],"state":"stopped","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":180,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":180,"one_line":{"line1":"MOONLIGHT SUNRISE - TWICE"},"two_line":{"line1":"MOONLIGHT SUNRISE","line2":"TWICE"},"three_line":{"line1":"MOONLIGHT SUNRISE","line2":"TWICE","line3":"MOONLIGHT SUNRISE"},"image_key":"d3f9f8605d9537facfe1824773f2ee62","artist_image_keys":["599f6a1fecedde053ff85c6c33eb0aba"]}}],"zones_seek_changed":[{"zone_id":"16015224073fb032eb45b23644eb8f5a209d","queue_time_remaining":180,"seek_position":null}]}

01/26 18:11:42 Info: sleep 104ms after flush

01/26 18:11:42 Info: [library/albumdetails] found 1 ingroup alternates, 1 other alternates

01/26 18:11:42 Info: [library/albumdetails] found 1 ingroup alternates, 1 other alternates

01/26 18:11:42 Info: [library/albumdetails] found 1 ingroup alternates, 1 other alternates

01/26 18:11:42 Trace: [music/searchindex] [search-index] removed in 0ms: 1 albums, 1 tracks, 0 works, 1 performers, 0 labels, 0 genres

01/26 18:11:42 Trace: [music/searchindex] [search-index] added in 1ms: 1 albums, 1 tracks, 0 works, 1 performers, 0 labels, 0 genres

01/26 18:11:42 Trace: GetImageData[Local](id=1062487 spec=128 key=xslicaaa) => gotfromcache=False missinglocalimage=False didscale=True scaletime=30), overalltime=33ms

01/26 18:11:42 Trace: GetImageData[Local](id=1062487 spec=128 key=xslicaaa) => gotfromcache=False missinglocalimage=False didscale=True scaletime=30), overalltime=33ms

01/26 18:11:42 Trace: [roonapi] [apiclient 192.168.2.4:42374] CONTINUE Changed {"zones_changed":[{"zone_id":"16015224073fb032eb45b23644eb8f5a209d","display_name":"Tambaqui","outputs":[{"output_id":"17015224073fb032eb45b23644eb8f5a209d","zone_id":"16015224073fb032eb45b23644eb8f5a209d","can_group_with_output_ids":["1701db8f072065fefe3359ce2c1d626a6569","17015224073fb032eb45b23644eb8f5a209d","170181b110a21e60345ab368c8f14f30e36e"],"display_name":"Tambaqui","volume":{"type":"db","min":-80,"max":0,"value":-12,"step":1,"is_muted":false,"hard_limit_min":-80,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"Mola-Mola Tambaqui","supports_standby":false,"status":"selected"}]}],"state":"loading","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":180,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":180,"one_line":{"line1":"MOONLIGHT SUNRISE - TWICE"},"two_line":{"line1":"MOONLIGHT SUNRISE","line2":"TWICE"},"three_line":{"line1":"MOONLIGHT SUNRISE","line2":"TWICE","line3":"MOONLIGHT SUNRISE"},"image_key":"d3f9f8605d9537facfe1824773f2ee62","artist_image_keys":["599f6a1fecedde053ff85c6c33eb0aba"]}}]}

01/26 18:11:43 Debug: [music/profilestats] Computed stats in 1025ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:44 Debug: [music/profilestats] Computed stats in 1360ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:46 Debug: [music/profilestats] Computed stats in 1614ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:47 Debug: [music/profilestats] Computed stats in 1563ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:47 Debug: [easyhttp] [43166] POST to https://device-map.roonlabs.net/1/register returned after 884 ms, status code: 200, request body size: 6 KB

01/26 18:11:47 Trace: [devicemap] device map updated

01/26 18:11:49 Debug: [music/profilestats] Computed stats in 1563ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:50 Info: [stats] 24319mb Virtual, 3219mb Physical, 959mb Managed, 403 Handles, 100 Threads

01/26 18:11:50 Debug: [music/profilestats] Computed stats in 1532ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:52 Debug: [music/profilestats] Computed stats in 1554ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:54 Debug: [music/profilestats] Computed stats in 1550ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:55 Debug: [music/profilestats] Computed stats in 1539ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:56 Debug: [music/profilestats] Computed stats in 1445ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:58 Debug: [music/profilestats] Computed stats in 1519ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:11:58 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [LOADING @ 0:00] MOONLIGHT SUNRISE - TWICE

01/26 18:12:00 Debug: [music/profilestats] Computed stats in 1606ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:12:01 Debug: [music/profilestats] Computed stats in 1565ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:12:03 Debug: [music/profilestats] Computed stats in 1544ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:12:04 Debug: [music/profilestats] Computed stats in 1561ms, got 50 top albums, 50 top performers, 74 genres

01/26 18:12:05 Info: [stats] 24215mb Virtual, 3219mb Physical, 972mb Managed, 403 Handles, 83 Threads

01/26 18:12:05 Debug: [music/profilestats] Computed stats in 509ms, got 50 top albums, 50 top performers, 19 genres

01/26 18:12:05 Trace: [dbperf] flush 0 bytes, 0 ops in 5850 ms (cumulative 202346728 bytes, 197395 ops in 744279 ms)

01/26 18:12:05 Trace: [library] endmutation in 23285ms

01/26 18:12:05 Trace: [dbperf] flush 0 bytes, 0 ops in 4 ms (cumulative 202346728 bytes, 197395 ops in 744283 ms)

01/26 18:12:05 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [LOADING @ 0:00] MOONLIGHT SUNRISE - TWICE

01/26 18:12:05 Info: [library/albumdetails] found 1 ingroup alternates, 0 other alternates

01/26 18:12:05 Info: [library/albumdetails] Created album details screen for LibraryAlbum[1808175, TWICE - MOONLIGHT SUNRISE]

01/26 18:12:05 Info: [library/albumdetails] 3e01666db9533017b446917a8accfe3fe55a, 3e01cb3a34d964f3784c949142a9ad1b2549, 3e018527134f17eb074798760a69f096f240, a600323731393634393836, a600323732323537323831, a600323731323039393738, a600323730363934393238, c80062626970696a716f327a366562, c8007277666d68677669316e726663, cd00346f3846395a71425667595f5a3530493359

01/26 18:12:05 Info: [library/albumdetails] found 1 ingroup alternates, 0 other alternates

01/26 18:12:05 Info: [library/albumdetails] Created album details screen for LibraryAlbum[1808175, TWICE - MOONLIGHT SUNRISE]

01/26 18:12:05 Info: [library/albumdetails] 3e01666db9533017b446917a8accfe3fe55a, 3e01cb3a34d964f3784c949142a9ad1b2549, 3e018527134f17eb074798760a69f096f240, a600323731393634393836, a600323732323537323831, a600323731323039393738, a600323730363934393238, c80062626970696a716f327a366562, c8007277666d68677669316e726663, cd00346f3846395a71425667595f5a3530493359

01/26 18:12:05 Trace: [music/query] performing tag query

01/26 18:12:05 Trace: [music/query] query returned 1 Sooloos.Broker.Api.Tag(s)

01/26 18:12:05 Trace: [music/query] performing tag query

01/26 18:12:05 Trace: [music/query] query returned 1 Sooloos.Broker.Api.Tag(s)

01/26 18:12:05 Trace: [music/query] performing tag query

01/26 18:12:05 Trace: [music/query] query returned 1 Sooloos.Broker.Api.Tag(s)

01/26 18:12:05 Trace: [music/query] performing album query

01/26 18:12:05 Trace: [music/query] query returned 150 Sooloos.Broker.Api.AlbumLite(s)

01/26 18:12:05 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 202346728 bytes, 197395 ops in 744285 ms)

01/26 18:12:05 Info: [Tambaqui] [zoneplayer] Playing: /storage/music/flac/Kpop/Twice/Twice - Moonlight Sunrise/MOONLIGHT SUNRISE.flac

01/26 18:12:05 Info: [library] saved recent ProfileId=b575db24-1ef0-4f48-9f2f-584db9bf194c Time=1/26/2023 7:12:05 AM DataType=album Type=long_nav MetadataId=50828862 ContentId=1808175 LibraryId=1808175 Text= Genre=

01/26 18:12:05 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 202346728 bytes, 197395 ops in 744286 ms)

01/26 18:12:05 Info: [Tambaqui] [zoneplayer] Open Result (Playing):Result[Status=Success]

01/26 18:12:05 Info: [Tambaqui] [zoneplayer] Starting playback

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=24, samplerate=44100, isdts=False) streamid=1714697270

01/26 18:12:05 Info: [zone Tambaqui] OnPlayFeedback Playing

01/26 18:12:05 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [PLAYING @ 0:00] MOONLIGHT SUNRISE - TWICE

01/26 18:12:05 Trace: [roonapi] [apiclient 192.168.2.4:42374] CONTINUE Changed {"zones_changed":[{"zone_id":"16015224073fb032eb45b23644eb8f5a209d","display_name":"Tambaqui","outputs":[{"output_id":"17015224073fb032eb45b23644eb8f5a209d","zone_id":"16015224073fb032eb45b23644eb8f5a209d","can_group_with_output_ids":["1701db8f072065fefe3359ce2c1d626a6569","17015224073fb032eb45b23644eb8f5a209d","170181b110a21e60345ab368c8f14f30e36e"],"display_name":"Tambaqui","volume":{"type":"db","min":-80,"max":0,"value":-12,"step":1,"is_muted":false,"hard_limit_min":-80,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"Mola-Mola Tambaqui","supports_standby":false,"status":"selected"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":180,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":180,"one_line":{"line1":"MOONLIGHT SUNRISE - TWICE"},"two_line":{"line1":"MOONLIGHT SUNRISE","line2":"TWICE"},"three_line":{"line1":"MOONLIGHT SUNRISE","line2":"TWICE","line3":"MOONLIGHT SUNRISE"},"image_key":"d3f9f8605d9537facfe1824773f2ee62","artist_image_keys":["599f6a1fecedde053ff85c6c33eb0aba"]}}]}

01/26 18:12:05 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/180 sec

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] synced to endpoint Mola-Mola Tambaqui clock: realtime=105204506423275 rtt=500us offset=90317677423us delta=90317677423us

01/26 18:12:05 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] SENT [112]{"request":"stream","stream_id":1714697270,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}

01/26 18:12:05 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [112] {"status":"Buffering"}

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] Endpoint Mola-Mola Tambaqui State Changed: Prepared => Buffering

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] [zoneplayer/raat] selected Mola-Mola Tambaqui as clock master

01/26 18:12:05 Debug: [raat/tcpaudiosource] connecting to 192.168.2.13:34153

01/26 18:12:05 Debug: [raat/tcpaudiosource] connected

01/26 18:12:05 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [112] {"status":"Ready"}

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] Endpoint Mola-Mola Tambaqui State Changed: Buffering => Ready

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] wait for ready in 32ms

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] Adjusting playback start offset from 50ms to 124ms

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device

01/26 18:12:05 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] SENT [113]{"request":"start","min_offset":124370975,"stream_sample":0}

01/26 18:12:05 Trace: [Tambaqui] [zoneplayer/raat] Endpoint Mola-Mola Tambaqui State Changed: Ready => Playing

01/26 18:12:05 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [112] {"status":"Playing"}

01/26 18:12:05 Trace: [Mola-Mola Tambaqui @ 192.168.2.13:44119] [raatclient] GOT [113] {"status":"Success","time":105204682029406}

01/26 18:12:05 Trace: GetImageData[Local](id=1062487 spec=512 key=xslicaaa) => gotfromcache=False missinglocalimage=False didscale=True scaletime=35), overalltime=35ms

01/26 18:12:05 Trace: GetImageData[Local](id=1062487 spec=512 key=xslicaaa) => gotfromcache=False missinglocalimage=False didscale=True scaletime=35), overalltime=35ms

01/26 18:12:05 Trace: GetImageData[Local](id=1062487 spec=512 key=xslicaaa) => gotfromcache=False missinglocalimage=False didscale=True scaletime=47), overalltime=47ms

01/26 18:12:05 Trace: GetImageData[Local](id=1062487 spec=512 key=xslicaaa) => gotfromcache=False missinglocalimage=False didscale=True scaletime=47), overalltime=47ms

01/26 18:12:05 Trace: GetImageData[Remote](id=12175 spec=1024 key=hasaaaaa) => got from cache overalltime=5ms

01/26 18:12:05 Trace: GetImageData[Remote](id=5503 spec=1024 key=rdiaaaaa) => got from cache overalltime=9ms

01/26 18:12:05 Trace: GetImageData[Remote](id=12175 spec=1024 key=hasaaaaa) => got from cache overalltime=11ms

01/26 18:12:05 Trace: GetImageData[Remote](id=5503 spec=1024 key=rdiaaaaa) => got from cache overalltime=12ms

01/26 18:12:06 Info:

--[ SignalPath ]---------------------------------------------

SignalPath Quality = Enhanced

Elements:

Source Format=Flac 44100/16/2 BitRate=954 Quality=Lossless

UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless

ParametricEQ

Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless

Raat Device=Mola-Mola Tambaqui

Output OutputType=Local_Analog Quality=Lossless SubType= Model=

------------------------------------------------------------

01/26 18:12:06 Debug: [easyhttp] [43167] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 848 ms, status code: 200, request body size: 380 B

01/26 18:12:06 Info: [library/albumdetails] found 2 streaming service alternates

01/26 18:12:06 Debug: [easyhttp] [43168] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-au returned after 838 ms, status code: 200, request body size: 148 B

01/26 18:12:06 Debug: [easyhttp] [43174] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 850 ms, status code: 200, request body size: 219 B

01/26 18:12:06 Debug: [easyhttp] [43175] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-au returned after 850 ms, status code: 200, request body size: 87 B

01/26 18:12:06 Debug: [easyhttp] [43177] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-au returned after 856 ms, status code: 200, request body size: 87 B

01/26 18:12:06 Debug: [easyhttp] [43171] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-au returned after 864 ms, status code: 200, request body size: 87 B

01/26 18:12:06 Debug: [easyhttp] [43169] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 868 ms, status code: 200, request body size: 380 B

01/26 18:12:06 Debug: [easyhttp] [43170] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 865 ms, status code: 200, request body size: 219 B

01/26 18:12:06 Debug: [easyhttp] [43176] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 864 ms, status code: 200, request body size: 219 B

01/26 18:12:06 Debug: [easyhttp] [43173] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-au returned after 865 ms, status code: 200, request body size: 87 B

01/26 18:12:06 Debug: [easyhttp] [43172] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-au returned after 866 ms, status code: 200, request body size: 219 B

01/26 18:12:06 Info: [library/albumdetails] found 2 streaming service alternates

01/26 18:12:06 Debug: [easyhttp] [43178] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-au returned after 841 ms, status code: 200, request body size: 148 B

01/26 18:12:06 Debug: [easyhttp] [43179] GET to https://metadatatext.roonlabs.net/1/blobs?objectId=62:1:53b96d66-1730-46b4-917a-8accfe3fe55a&type=review&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-au returned after 881 ms, status code: 200, request body size: 0 B

01/26 18:12:06 Debug: [easyhttp] [43180] GET to https://metadatatext.roonlabs.net/1/blobs?objectId=191:0:710857&type=biography&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-au returned after 922 ms, status code: 200, request body size: 0 B

01/26 18:12:10 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [PLAYING @ 0:05/3:00] MOONLIGHT SUNRISE - TWICE

01/26 18:12:15 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [PLAYING @ 0:10/3:00] MOONLIGHT SUNRISE - TWICE

01/26 18:12:18 Trace: [volumewatcher] ev_VolumeChanged DidMount: /run/user/0

01/26 18:12:18 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /run/user/0

01/26 18:12:18 Debug: [broker/filebrowser/volumeattached] skipping /run/user/0 because it is not a /dev/sd[0-9]* (mountline: tmpfs /run/user/0 tmpfs rw,nosuid,nodev,relatime,size=805540k,mode=700 0 0)

01/26 18:12:20 Info: [stats] 24199mb Virtual, 3248mb Physical, 1006mb Managed, 406 Handles, 81 Threads

01/26 18:12:20 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [PLAYING @ 0:15/3:00] MOONLIGHT SUNRISE - TWICE

01/26 18:12:25 Trace: [Tambaqui] [Enhanced, 16/44 FLAC => 24/44] [100% buf] [PLAYING @ 0:20/3:00] MOONLIGHT SUNRISE - TWICE

Hey @Bluebeat,

Early reports of users on early access are scattered, so we’ll need to grab new timestamps and data to review as a next step. However, we’ll only need reports from users who are on early access for now :+1:

1 Like

@benjamin I can and will offer new timestamps when I have installed the production build, expected some time in the next days (?).

@benjamin If you want me to try the early access build, I will. Just let me know.

Just had a perfect gapless run of 4 24/96 tracks. Quite nice for a change. The 5th track of the same album (Nik Bärtsch Entendre, Qobuz) could not be reached without a considerable gap of about 10 seconds. Not surprising, but still disappointing. The last track didn’t play at all.

@benjamin I’d like to stress that is not only the gaps that this issue for me is about. In this lengthy thread that could be overlooked or forgotten. Tracks and albums are loading slowly or not at all and get abandoned mid track too. Especially high res, but also 16/44. It might have the same cause, it might not. I hope the upcoming build is targetting that part of the issue too. Keeping my fingers crossed …

2 Likes

@benjamin

Except for Tidal, which I haven’t tried yet, Build 1202 is definitely a big improvement for the issues I’ve reported in this thread. Great.

But.

I noticed the gaps are creeping up again when changing from one album to another or playing a track from another album, picked by Roon Radio. Certainly not big ones, but up to 2, maybe 3 seconds. Not consistent.

Also I had - after a two day run of trouble-free play - two tracks within the same album (John Coltrane, Coltrane 24/192 Qobuz) that had hickups, resulting in skipping one track.

I’ll keep reporting.

2 Likes