And there it happened again. At 16:19 it stopped and had to press play again to continue the TIDAL cue.
10/22 16:19:57 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Output OutputType=AirPlay Quality=HighQuality SubType=2 Model=Denon Home Sound Bar 550
------------------------------------------------------------
10/22 16:19:57 Info: [zone Werkkamer + Slaapkamer] OnPlayFeedback StoppedEndOfMediaNatural
10/22 16:19:57 Debug: [zone Werkkamer + Slaapkamer] _Advance (Track)
10/22 16:19:57 Trace: [library] finished with 2 dirty tracks 1 dirty albums 3 dirty performers 2 dirty works 2 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 9 changed objects
10/22 16:19:57 Trace: [music/searchindex] [search-index] removed in 0ms: 1 albums, 0 tracks, 0 works, 0 performers, 0 labels, 0 genres
10/22 16:19:57 Trace: [music/searchindex] [search-index] added in 3ms: 1 albums, 0 tracks, 0 works, 0 performers, 0 labels, 0 genres
10/22 16:19:57 Trace: [music/searchindex] totals: 2258 albums, 24872 tracks, 16067 works, 33484 performers, 932 labels, 1314 genres
10/22 16:19:57 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:19:57 Trace: computing profile stats
10/22 16:19:57 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:19:57 Trace: done computing profile stats
10/22 16:19:57 Trace: [roondns] flushed 8 last-known-good entries
10/22 16:19:57 Debug: [easyhttp] [1510] POST to http://ws.audioscrobbler.com/2.0/ returned after 158 ms, status code: 200, request body size: 252 B
10/22 16:19:57 Debug: [music/profilestats] Computed stats in 137ms, got 50 top albums, 50 top performers, 64 genres
10/22 16:19:57 Trace: GetImageData[Remote](id=12722 spec=500 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/500.jpg) => fetched in 687ms status=200 size=148127 overalltime=690ms
10/22 16:19:58 Trace: GetImageData[Remote](id=12722 spec=500 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/500.jpg) => fetched in 751ms status=200 size=148127 overalltime=765ms
10/22 16:19:58 Info: [stats] 6836mb Virtual, 1472mb Physical, 784mb Managed, 379 Handles, 82 Threads
10/22 16:19:58 Trace: GetImageData[Remote](id=12722 spec=256 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/256.jpg) => fetched in 1282ms status=200 size=43004 overalltime=1297ms
10/22 16:19:58 Trace: GetImageData[Remote](id=12722 spec=512 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/512.jpg) => fetched in 1449ms status=200 size=153798 overalltime=1451ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002). re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002). re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002). re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryTrack:2 dirty (< rebuild threshold of 19897.600000000002). re-sorting item-by-item (internaltype=LibraryTrack)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryTrack: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4). re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4). re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4). re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4). re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:00 Debug: [query] Sooloos.Broker.Music.LibraryAlbum:1 dirty (< rebuild threshold of 1806.4). re-sorting item-by-item (internaltype=LibraryAlbum)
10/22 16:20:00 Info: [query] Sooloos.Broker.Music.LibraryAlbum: Process Removes/Adds (small) in 0ms
10/22 16:20:01 Trace: [dbperf] flush 0 bytes, 0 ops in 13 ms (cumulative 0 bytes, 0 ops in 33750 ms)
10/22 16:20:01 Trace: [library] endmutation in 4481ms
10/22 16:20:01 Trace: [library/playlist] saving playlist folder 10:1:2fbd43db-3328-4514-91b5-3303e447c95c [root]
10/22 16:20:01 Trace: finished updating 4 dirty playlists and 1 dirty playlist folders
10/22 16:20:01 Trace: [library] endmutation in 25ms
10/22 16:20:01 Trace: [broker/accounts] doing auth refresh in EnsureAuthReady, next=10/22/2024 15:20:06
10/22 16:20:01 Trace: [broker/accounts] refreshing account info for email='roma@cybertown.nl' userid=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:01 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
10/22 16:20:01 Trace: [fiveaccountserver] BODY token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523
10/22 16:20:01 Trace: [dbperf] flush 0 bytes, 0 ops in 10 ms (cumulative 0 bytes, 0 ops in 33760 ms)
10/22 16:20:01 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 0 bytes, 0 ops in 33762 ms)
10/22 16:20:01 Trace: [roondns] flushed 8 last-known-good entries
10/22 16:20:01 Debug: [easyhttp] [1515] POST to https://api.roonlabs.net/accounts5/accounts/3/login returned after 239 ms, status code: 200, request body size: 42 B
10/22 16:20:01 Trace: [fiveaccountserver] GOT {"status":"Success","userid":"779e78f9-be8d-4cc4-af2f-98515f276a14","token":"eb0fee49-e0fa-4a25-a02c-b7a5b5030523","expiration":30,"email":"roma@cybertown.nl","groups":[]}
10/22 16:20:02 Debug: [easyhttp] [1517] GET to https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 306 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:20:02 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/profileslist?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523
10/22 16:20:02 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/userinfo?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523&machineid=cc1c8cb4-595e-b691-576b-aac45cf5d025
10/22 16:20:02 Trace: [broker/accounts] updated token. New expiration is 11/21/2024 16:20:06
10/22 16:20:02 Debug: [easyhttp] [1516] GET to https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 389 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Trace: [bits] myinfo: {"pushid":"broker/bd2dc65f-2d22-4a23-9f00-c5377233eda5","roon_auth_token":"eb0fee49-e0fa-4a25-a02c-b7a5b5030523","os":"Linux 4.4.180+","platform":"linuxx64","machineversion":200001470,"branch":"production","appmodifier":"","appname":"RoonServer"}
10/22 16:20:02 Trace: [airplay/client] Sending TEARDOWN
10/22 16:20:02 Trace: [airplay/client] Sending TEARDOWN
10/22 16:20:02 Debug: [easyhttp] [1519] GET to https://api.tidal.com/v1/tracks/117439337?countryCode=NL returned after 151 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337?countryCode=NL => Success
10/22 16:20:02 Trace: [airplay/client] TEARDOWN was successful
10/22 16:20:02 Info: [airplay] AirPlay device disconnected: AirPlayDevice[DeviceId=C2F85B428C22@Werkkamer._raop._tcp.local, Name=Werkkamer-.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.169:7000]
10/22 16:20:02 Trace: [airplay] disconnected
10/22 16:20:02 Trace: [zone] Werkkamer received transport control from endpoint integration: suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Werkkamer + Slaapkamer received transport control from Werkkamer (Denon Home Sound Bar 550): suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Suspend
10/22 16:20:02 Info: [zone Werkkamer + Slaapkamer] OnPlayFeedback Stopped
10/22 16:20:02 Info: [zone Werkkamer + Slaapkamer] Canceling Pending Sleep
10/22 16:20:02 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:20:02 Trace: [airplay/client] Sending FLUSH
10/22 16:20:02 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [STOPPED @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:20:02 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
10/22 16:20:02 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [STOPPED @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:20:02 Debug: [easyhttp] [1522] GET to https://api.tidal.com/v1/tracks/45409877?countryCode=NL returned after 152 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877?countryCode=NL => Success
10/22 16:20:02 Debug: [easyhttp] [1520] GET to https://api.roonlabs.net/accounts5/accounts/3/profileslist?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523 returned after 173 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [fiveaccountserver] GOT {"status":"Success","profiles":[{"id":"d2cc3a92-385f-4afa-b6f8-f2b57c6aea2f","name":"Robbert","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-779e78f9-be8d-4cc4-af2f-98515f276a14--d2cc3a92-385f-4afa-b6f8-f2b57c6aea2f.jpg","birthdate":"19650308","scrobbler_token":"39x1ejdTWKJyJahmuaRhRbLAt1F3VJ1X","facebook_auth":"{ \"accesstoken\": \"EAADeu8DcOV8BAEwckLFZBwqpyfSZCSucb9ouK4SVbJn6sjmukfuwHAnSk1ACqgY7PWXmZBslMGEL1ZAE9kKeqB0Yv2AMwzXjPYYsGDDDAQoZAQRVcVXZBhgnySFkpTdPl2Ncbq0TPRjBjRzLINvNj1xZAf2gsKBhDEmng7bKUOguf9RZCztTZCJqh\" }"}]}
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Debug: [easyhttp] [1521] GET to https://api.roonlabs.net/accounts5/accounts/3/userinfo?token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523&machineid=cc1c8cb4-595e-b691-576b-aac45cf5d025 returned after 183 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [fiveaccountserver] GOT {"user":{"tfa":{"enabled":true},"userid":"779e78f9-be8d-4cc4-af2f-98515f276a14","firstname":"Robbert","lastname":"Marrink","email":"roma@cybertown.nl","joinmailinglist":false,"allowpushnotifications":true,"class":"Normal","groups":[],"dncs":["company_news","roon_software_news","roon_software_promos","roon_store_promos"],"trialallowed":false},"status":"Success"}
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Trace: [airplay/client] TEARDOWN was successful
10/22 16:20:02 Info: [airplay] AirPlay device disconnected: AirPlayDevice[DeviceId=FADE9C8D6810@Slaapkamer._raop._tcp.local, Name=Slaapkamer-2.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.226:7000]
10/22 16:20:02 Trace: [airplay] disconnected
10/22 16:20:02 Trace: [zone] Slaapkamer received transport control from endpoint integration: suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Werkkamer + Slaapkamer received transport control from Slaapkamer (Denon Home Sound Bar 550): suspend
10/22 16:20:02 Trace: [zone Werkkamer + Slaapkamer] Suspend
10/22 16:20:02 Info: [zone Werkkamer + Slaapkamer] Canceling Pending Sleep
10/22 16:20:02 Warn: [airplay/client] FLUSH failed: 455 Method Not Valid In This State
10/22 16:20:02 Debug: [easyhttp] [1526] GET to https://api.roonlabs.net/metadata/1/albums/166:0:45409871/credits?c=tidal-nl&tidal=max returned after 162 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Debug: [easyhttp] [1527] GET to https://api.roonlabs.net/metadata/1/albums/166:0:45409871?c=tidal-nl&tidal=max returned after 168 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Debug: [easyhttp] [1525] GET to https://api.roonlabs.net/metadata/1/albums/166:0:45409871/tracks?c=tidal-nl&tidal=max returned after 184 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Info: [metadata/albumdetails] Created album details screen for a6003435343039383731
10/22 16:20:02 Info: [metadata/albumdetails] 79004d5730303032383432393039,3e01e996cf1975dbd643a6d3e4266e4b8319,3e010b5f1f348a182f47994e1c18147fa0f2,79004d5230303034343038363437,a6003435343039383731,c80030383836343435323035353033,cd0054595f52774477504f523751687578626255
10/22 16:20:02 Debug: [easyhttp] [1523] POST to https://api.roonlabs.net/bits/1/q/roon.base.,roon.internet_discovery.,roon.debug.,roon.broker.,roon.dsp.,roon.sood.?roon_auth_token=eb0fee49-e0fa-4a25-a02c-b7a5b5030523 returned after 394 ms, status code: 200, request body size: 246 B
10/22 16:20:02 Trace: [bits] updated bits, in 402ms
10/22 16:20:02 Debug: [easyhttp] [1524] GET to https://api.roonlabs.net/profileimages/five-779e78f9-be8d-4cc4-af2f-98515f276a14--d2cc3a92-385f-4afa-b6f8-f2b57c6aea2f.jpg?random=7c36b0db-2647-4695-8a56-325dd30864dc returned after 332 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=779e78f9-be8d-4cc4-af2f-98515f276a14
10/22 16:20:02 Debug: [easyhttp] [1530] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:1291077&type=biography&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 126 ms, status code: 200, request body size: 0 B
10/22 16:20:02 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 0 bytes, 0 ops in 33763 ms)
10/22 16:20:02 Debug: [easyhttp] [1528] POST to https://api.roonlabs.net/metadata/1/albums/translate?c=tidal-nl&tidal=max returned after 207 ms, status code: 200, request body size: 192 B
10/22 16:20:02 Trace: [library] endmutation in 12ms
10/22 16:20:02 Info: [library/albumdetails] found 1 streaming service alternates
10/22 16:20:03 Debug: [easyhttp] [1529] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=121:0:MW0002842909&type=review&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 216 ms, status code: 200, request body size: 0 B
10/22 16:20:03 Debug: [easyhttp] [1531] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:1271047&type=biography&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 217 ms, status code: 200, request body size: 0 B
10/22 16:20:03 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 0 bytes, 0 ops in 33765 ms)
10/22 16:20:03 Trace: GetImageData[Remote](id=4398 spec=16by7_2048 key=engaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303032383433373434/photo/16by7_2048.jpg) => fetched in 201ms status=200 size=259452 overalltime=235ms
10/22 16:20:03 Debug: [easyhttp] [1532] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:3900902&type=biography&sourceLangs=Wikipedia:nl,Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=tidal-nl&contentPreferences=reallyAvoidMqa&tidal=max returned after 280 ms, status code: 200, request body size: 0 B
10/22 16:20:03 Trace: GetImageData[Remote](id=4397 spec=1by1_1024 key=dngaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303032383433373434/avatar/1by1_1024.jpg) => fetched in 794ms status=200 size=244783 overalltime=841ms
10/22 16:20:12 Debug: [easyhttp] [1535] POST to https://api.roonlabs.net/device-map/1/register returned after 177 ms, status code: 200, request body size: 6 KB
10/22 16:20:12 Trace: [devicemap] device map updated
10/22 16:20:13 Info: [stats] 6788mb Virtual, 1472mb Physical, 795mb Managed, 365 Handles, 72 Threads
10/22 16:20:14 Trace: [library] endmutation in 12ms
10/22 16:20:18 Trace: [library] endmutation in 11ms
10/22 16:20:23 Trace: [library] endmutation in 11ms
10/22 16:20:24 Trace: [library] endmutation in 11ms
10/22 16:20:24 Trace: [library] endmutation in 12ms
10/22 16:20:24 Trace: [library] endmutation in 11ms
10/22 16:20:26 Trace: GetImageData[Remote](id=4574 spec=16by7_2048 key=ytgaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303030373737373835/photo/16by7_2048.jpg) => fetched in 223ms status=200 size=99474 overalltime=238ms
10/22 16:20:26 Trace: GetImageData[Remote](id=4573 spec=1by1_1024 key=xtgaaaaa uri=https://imagecache.roonlabs.net/im/1/artists/7a004d4e30303030373737373835/avatar/1by1_1024.jpg) => fetched in 262ms status=200 size=132405 overalltime=292ms
10/22 16:20:28 Info: [stats] 6748mb Virtual, 1472mb Physical, 797mb Managed, 365 Handles, 67 Threads
10/22 16:20:30 Trace: [library] endmutation in 12ms
10/22 16:20:36 Trace: [library] endmutation in 12ms
10/22 16:20:43 Info: [stats] 6748mb Virtual, 1472mb Physical, 797mb Managed, 365 Handles, 68 Threads
10/22 16:20:53 Trace: [library] endmutation in 11ms
10/22 16:20:54 Trace: [library] endmutation in 11ms
10/22 16:20:54 Trace: [library] endmutation in 12ms
10/22 16:20:54 Trace: [library] endmutation in 11ms
10/22 16:20:54 Trace: [library] endmutation in 11ms
10/22 16:20:58 Info: [stats] 6788mb Virtual, 1472mb Physical, 765mb Managed, 365 Handles, 76 Threads
10/22 16:21:13 Info: [stats] 6764mb Virtual, 1472mb Physical, 765mb Managed, 365 Handles, 69 Threads
10/22 16:21:20 Trace: [library] endmutation in 11ms
10/22 16:21:24 Trace: [library] endmutation in 13ms
10/22 16:21:24 Trace: [library] endmutation in 11ms
10/22 16:21:24 Trace: [library] endmutation in 12ms
10/22 16:21:24 Trace: [library] endmutation in 11ms
10/22 16:21:28 Info: [stats] 6756mb Virtual, 1472mb Physical, 766mb Managed, 365 Handles, 68 Threads
10/22 16:21:29 Trace: [library] endmutation in 11ms
10/22 16:21:43 Info: [stats] 6740mb Virtual, 1472mb Physical, 766mb Managed, 365 Handles, 70 Threads
10/22 16:21:58 Info: [stats] 6892mb Virtual, 1472mb Physical, 767mb Managed, 365 Handles, 89 Threads
10/22 16:22:13 Info: [stats] 6756mb Virtual, 1472mb Physical, 767mb Managed, 365 Handles, 68 Threads
10/22 16:22:14 Trace: [library] endmutation in 11ms
10/22 16:22:17 Trace: [zone Werkkamer + Slaapkamer] PlayPause
10/22 16:22:17 Trace: [zone Werkkamer + Slaapkamer] Selecting Source state=Stopped
10/22 16:22:17 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:17 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:17 Debug: [easyhttp] [1540] GET to https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 167 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:22:17 Debug: [easyhttp] [1539] GET to https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM returned after 173 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877/playbackinfopostpaywall?countryCode=NL&audioquality=HI_RES_LOSSLESS&assetpresentation=FULL&playbackmode=STREAM => Success
10/22 16:22:17 Debug: [easyhttp] [1541] GET to https://api.tidal.com/v1/tracks/117439337?countryCode=NL returned after 51 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/117439337?countryCode=NL => Success
10/22 16:22:17 Debug: [easyhttp] [1542] GET to https://api.tidal.com/v1/tracks/45409877?countryCode=NL returned after 46 ms, status code: 200, request body size: 0 B
10/22 16:22:17 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/45409877?countryCode=NL => Success
10/22 16:22:17 Info: [Werkkamer + Slaapkamer] [zoneplayer] Playing: https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZmFkYzViYjlhMTNmZDllNTE5Yjc5ZTFmYTM5ZmExYV82MS5tcDQ/0.flac
10/22 16:22:17 Info: [Werkkamer + Slaapkamer] [zoneplayer] Queueing: https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKAgDEiQ3NGMwODQ1ZmNkMTk0NmIwMTYwZjg3ZTA5Y2I0ZTEyZi5tcDQ/0.flac
10/22 16:22:17 Info: FTMSI-B new FileCache ti/93C7735A
>>> /var/packages/RoonServer/shares/RoonServer/RoonOnNAS/RoonServer/Cache/smc.db/bfc/58.cache
>>> https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZmFkYzViYjlhMTNmZDllNTE5Yjc5ZTFmYTM5ZmExYV82MS5tcDQ/0.flac
10/22 16:22:17 Debug: FTMSI-B Cache open file ti/93C7735A domain: zoneplayer:7 ordinal:11
10/22 16:22:17 Debug: FTMSI-B ti/93C7735A download status: DownloadNotStarted accessTimeout:False openFiles:1 prev:no
10/22 16:22:17 Info: FTMSI-B ti/93C7735A: allocated bw changed from 0 to 51200 kbps
10/22 16:22:17 Info: FTMSI-B 1 FileCache ti/93C7735A dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':11) --> bw limit:51200kbps
10/22 16:22:17 Info: FTMSI-B 2 FileCache ti/873605B6 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':74) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B 3 FileCache ti/BDD8D5DD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':75) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B 4 FileCache ti/6BDF080C dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:7':9) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B 5 FileCache ti/D456F5C5 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:7':10) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B FileCache removed ti/6BDF080C
10/22 16:22:17 Debug: FTMSI-B-OE ti/6BDF080C exit thread signalled
10/22 16:22:17 Trace: FTMSI-B 1 FileCache ti/93C7735A dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':11) --> bw limit:51200kbps
10/22 16:22:17 Trace: FTMSI-B 2 FileCache ti/873605B6 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':74) --> bw limit:0kbps
10/22 16:22:17 Debug: FTMSI-B-OE ti/6BDF080C exit thread signalled
10/22 16:22:17 Trace: FTMSI-B 3 FileCache ti/BDD8D5DD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':75) --> bw limit:0kbps
10/22 16:22:17 Trace: FTMSI-B 4 FileCache ti/D456F5C5 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:7':10) --> bw limit:0kbps
10/22 16:22:17 Debug: FTMSI-B-OE ti/93C7735A created new req 1 for block 0 p 4294967295; active requests 1
10/22 16:22:17 Info: FTMSI-B new FileCache ti/D879AAF7
>>> /var/packages/RoonServer/shares/RoonServer/RoonOnNAS/RoonServer/Cache/smc.db/bfc/59.cache
>>> https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKAgDEiQ3NGMwODQ1ZmNkMTk0NmIwMTYwZjg3ZTA5Y2I0ZTEyZi5tcDQ/0.flac
10/22 16:22:17 Debug: FTMSI-B Cache open file ti/D879AAF7 domain: zoneplayer:7 ordinal:12
10/22 16:22:17 Debug: FTMSI-B ti/D879AAF7 download status: DownloadNotStarted accessTimeout:False openFiles:1 prev:no
10/22 16:22:17 Info: FTMSI-B FileCache removed ti/D456F5C5
10/22 16:22:17 Debug: FTMSI-B-OE ti/D456F5C5 exit thread signalled
10/22 16:22:17 Trace: FTMSI-B 1 FileCache ti/93C7735A dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':11) --> bw limit:51200kbps
10/22 16:22:17 Trace: FTMSI-B 2 FileCache ti/873605B6 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':74) --> bw limit:0kbps
10/22 16:22:17 Trace: FTMSI-B 3 FileCache ti/BDD8D5DD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:2':75) --> bw limit:0kbps
10/22 16:22:17 Trace: FTMSI-B 4 FileCache ti/D879AAF7 dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:7':12) --> bw limit:0kbps
10/22 16:22:17 Info: FTMSI-B FileCache disposed ti/6BDF080C
10/22 16:22:17 Debug: FTMSI-B-OE ti/D456F5C5 exit thread signalled
10/22 16:22:17 Info: FTMSI-B FileCache disposed ti/D456F5C5
10/22 16:22:18 Debug: [easyhttp] [1543] GET to https://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZmFkYzViYjlhMTNmZDllNTE5Yjc5ZTFmYTM5ZmExYV82MS5tcDQ/0.flac?token=1729610406~YmMzNGU1OTQxNTNmM2FjYzZjYWM2ZDdiNmUyM2E4MjVjZDFmNjZhMQ== returned after 126 ms, status code: 200, request body size: 0 B
10/22 16:22:18 Debug: FTMSI-B got length for ti/93C7735A; 40.4 MBytes
10/22 16:22:18 Debug: FTMSI-B ti/93C7735A download status: FileLengthRetrieved accessTimeout:False openFiles:1 prev:(DownloadNotStarted,False,1)
10/22 16:22:18 Debug: FTMSI-B-OE set min bandwidth for ti/93C7735A to 872 kbps
10/22 16:22:18 Info: FTMSI-B-OE ti/93C7735A rid:1 response took 127ms
10/22 16:22:18 Debug: FTMSI-B ti/93C7735A download status: FirstBlockRetrieved accessTimeout:False openFiles:1 prev:(FileLengthRetrieved,False,1)
10/22 16:22:18 Info: [Werkkamer + Slaapkamer] [zoneplayer] Open Result (Playing):Result[Status=Success]
10/22 16:22:18 Info: [Werkkamer + Slaapkamer] [zoneplayer] Starting playback
10/22 16:22:18 Trace: [airplay/client] Connecting to airplay server
10/22 16:22:18 Trace: [airplay/client] Connecting to airplay server
10/22 16:22:18 Debug: Lastfm '39x1ejdTWKJyJahmuaRhRbLAt1F3VJ1X' START: The Chris Robinson Brotherhood - Burn Slow
10/22 16:22:18 Info: [zone Werkkamer + Slaapkamer] OnPlayFeedback Playing
10/22 16:22:18 Trace: [Werkkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:18 Trace: [Slaapkamer] [HighQuality, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Burn Slow - The Chris Robinson Brotherhood / Chris Robinson Band
10/22 16:22:18 Trace: [airplay/client] Requesting OPTIONS
10/22 16:22:18 Info: [airplay/client] REQUESTING OPTIONS *
10/22 16:22:18 Trace: [airplay/client] Requesting OPTIONS
10/22 16:22:18 Info: [airplay/client] REQUESTING OPTIONS *
10/22 16:22:18 Trace: [airplay/client] Got good OPTIONS: ANNOUNCE, SETUP, RECORD, PAUSE, FLUSH, FLUSHBUFFERED, TEARDOWN, OPTIONS, POST, GET, PUT
10/22 16:22:18 Trace: [airplay] connected
10/22 16:22:18 Info: [airplay] AirPlay device connected: AirPlayDevice[DeviceId=C2F85B428C22@Werkkamer._raop._tcp.local, Name=Werkkamer-.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.169:7000]
10/22 16:22:18 Trace: [airplay/client] Sending ANNOUNCE
10/22 16:22:18 Trace: [airplay/client] Got good OPTIONS: ANNOUNCE, SETUP, RECORD, PAUSE, FLUSH, FLUSHBUFFERED, TEARDOWN, OPTIONS, POST, GET, PUT
10/22 16:22:18 Info: [airplay] AirPlay device connected: AirPlayDevice[DeviceId=FADE9C8D6810@Slaapkamer._raop._tcp.local, Name=Slaapkamer-2.local, Model=Denon Home Sound Bar 550, IPEndPoint=10.0.1.226:7000]
10/22 16:22:18 Trace: [airplay] connected
10/22 16:22:18 Trace: [airplay/client] Sending ANNOUNCE
10/22 16:22:18 Trace: [airplay/client] ANNOUNCE Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SETUP
10/22 16:22:18 Trace: [airplay/client] ANNOUNCE Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SETUP
10/22 16:22:18 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/455 sec
10/22 16:22:18 Trace: [airplay/client] SETUP was successful
10/22 16:22:18 Trace: [airplay/client] Sending RECORD
10/22 16:22:18 Trace: [airplay/client] SETUP was successful
10/22 16:22:18 Trace: [airplay/client] Sending RECORD
10/22 16:22:18 Trace: [airplay/client] Record Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SET_PARAMETER volume -24db
10/22 16:22:18 Trace: [airplay/client] Record Succeeded
10/22 16:22:18 Trace: [airplay/client] Sending SET_PARAMETER volume -21db
10/22 16:22:18 Debug: [easyhttp] [1544] POST to http://ws.audioscrobbler.com/2.0/ returned after 132 ms, status code: 200, request body size: 292 B
10/22 16:22:18 Trace: [airplay/client] Sending first sync packet rtptime=316741943 synctime=316830143
10/22 16:22:18 Trace: [airplay/client] Sending first sync packet rtptime=177012695 synctime=177100895
10/22 16:22:18 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Output OutputType=AirPlay Quality=HighQuality SubType=2 Model=Denon Home Sound Bar 550
------------------------------------------------------------
10/22 16:22:18 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Output OutputType=AirPlay Quality=HighQuality SubType=2 Model=Denon Home Sound Bar 550
------------------------------------------------------------
10/22 16:22:18 Trace: [mdns/dacp] [airplay/dacp] got command 'setvolume' value=-21
10/22 16:22:18 Trace: [mdns/dacp] [airplay/dacp] got command 'setvolume' value=-24
10/22 16:22:18 Trace: [airplay/client] Sending SET_PARAMETER volume -21db
10/22 16:22:18 Trace: GetImageData[Remote](id=12722 spec=500 key=ivsaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/a6003435343039383731/cover/500.jpg) => fetched in 61ms status=200 size=148127 overalltime=68ms
> Discontinued due to limitation in characters here.