Roon + TIDAL frequent errors preventing streaming

Did this, but it didn’t help.
No skipping so far, but tracks are loading very slow.
As I said earlier, some tracks start in a 30-40-60 sec.

A few screenshots to illustrate slow loading Tidal pages:

Reloaded page a few times and a little later:

But there are no “Genres” even.

Qobuz is ok though.

Hi @Serge_Tse ,

Thanks for the logs and screenshots, really appreciate the way you laid these out, nice and neat! :slight_smile:

Looking over your second log, it appears that you also lost connection to our servers around the same time the issue started, I suspect there may be some network variables at play here.

Do you live in an area prone to internet outages? Are you able to play local content when the issue is occurring?

Testing another Core is also a good step to try, that way we can further confirm if the issue is ROCK-specific or it happens with all Cores on the network, I have left steps for this below:

  • Create a Backup of your current database
  • Open Roon on the other PC you wish to try as the Core
  • Roon Settings → General
  • Disconnect
  • On the “Choose your Core” screen, press “Use this PC”
  • If asked to Unauthorize, you can go ahead and do so. You are limited to one active Roon Core at a time but you are free to switch between them as often as you’d like.
  • Verify if the same behavior occurs on the different PC

Thanks!

Hi, @noris
I moved Roon Core to Windows 10, restored database from backup and left Roon to play for 6 hours
So far I didn’t see any skips in the history and log. Even though the Windows laptop is older and slower than NUC. Only a few slow loadings (5-10 sec.)
Will watch again this evening.
It’s wierd that Roon on Windows found some “new” albums on NAS that were marked as corrupted files by ROCK.

Current setup:
Roon Core - Laptop Fujitsu Lifebook AH552/SL (Intel Core i5-3230 2.60GHz, 8Gb RAM, GPU Intel HD Graphics 4000, Windows 10 Pro 64 bit), Roon Version - 1.8 (build 806) stable
Roon Bridge - RPi4 (HiFiBerry Digi+ Pro Bundle - HiFiBerryOS 20210531)
Network Router - TP-Link Archer C2300

Connections:
Core → ethernet cable → Powerline-adapters → ethernet cable → router
RPi → ethernet cable → router
RPi → toslink - > Hegel H390
DNS - from ISP

1 Like

Last evening I moved Roon Core to Linux and the skippings returned :disappointed_relieved:
And the message “TIDAL login failed”

“Mother (ultimate mix) - John Lennon” - played
“Loaded Gun - The Dirty Knobs” - skipped
“Age of Machine - Greta Van Fleet” - played but I paused it manually

Current setup:
Roon Core - Intel NUC 8i5BEH2 (16Gb RAM, 512Gb M.2 SSD) - Linux Mint MATE 64 bit, Roon Server Version 1.8 (build 806) stable.
Roon Bridge - RPi4 (HiFiBerry Digi+ Pro Bundle - HiFiBerryOS 20210531)
Network Router - TP-Link Archer C2300

Connections:
Core → ethernet cable → Powerline-adapters → ethernet cable → router (also tried directly to the router)
RPi → ethernet cable → router
RPi → toslink - > Hegel H390
DNS - from ISP

Logs:

Part 1
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 48000/24/2  Quality=Lossless MQA 96000
    MQA Subtype=authentication LightState=off OriginalSampleRate=48000 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:19:58 Info: MQA authentication
07/24 11:19:58 Info: [zone HiFiBerry Digi+] OnPlayFeedback StoppedEndOfMediaNatural
07/24 11:19:58 Debug: [zone HiFiBerry Digi+] _Advance
07/24 11:19:58 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [548] {"status":"Ended"}
07/24 11:19:58 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1012] {"status":"Success"}
07/24 11:19:58 Trace: [HiFiBerry Digi+] [Lossless 96,1x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [LOADING @ 0:00] Mother (ultimate mix) - John Lennon
07/24 11:19:58 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/174979689/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
07/24 11:19:58 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/132367400/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
07/24 11:19:58 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/174979689?countryCode=AR => Success
07/24 11:19:58 Info: [HiFiBerry Digi+] [zoneplayer] Playing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInNzYwYTVkMGExMWEyMDA1YTBmNWZjNWMyYjFkMWVjMjVfNjAubXA0/0.flac
07/24 11:19:58 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
07/24 11:19:58 Debug: [raat/tcpaudiosource] disconnecting
07/24 11:19:58 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
07/24 11:19:58 Info: [audio/env] [zoneplayer] All streams were disposed
07/24 11:19:58 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/132367400?countryCode=AR => Success
07/24 11:19:58 Info: sleep 39ms after flush
07/24 11:19:58 Info: [HiFiBerry Digi+] [zoneplayer] Queueing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDYwNjYxYmIwOTJjYTk1OTQ1YWE5NjM1ODE2MzkzN2ZfNjAubXA0/0.flac
07/24 11:19:58 Trace: [streamingmediafile] [6] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDYwNjYxYmIwOTJjYTk1OTQ1YWE5NjM1ODE2MzkzN2ZfNjAubXA0/0.flac] [cachedbytes=0/-1] starting caching...
07/24 11:19:58 Info: [HiFiBerry Digi+] [zoneplayer]     Open Result (Playing):Result[Status=Success]
07/24 11:19:58 Info: [HiFiBerry Digi+] [zoneplayer] Starting playback
07/24 11:19:58 Trace: [HiFiBerry Digi+] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=24, samplerate=48000, isdts=False, mqa=192000) => StreamFormat(channels=2, bitspersample=24, samplerate=48000, isdts=False, mqa=192000) streamid=46902529
07/24 11:19:58 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1016]{"request":"teardown"}
07/24 11:19:58 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Prepared => Idle
07/24 11:19:58 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1017]{"request":"setup","format":{"sample_type":"pcm","sample_rate":48000,"bits_per_sample":24,"channels":2,"sample_subtype":"mqa","mqa_original_sample_rate":192000}}
07/24 11:19:58 Info: [zone HiFiBerry Digi+] OnPlayFeedback Playing
07/24 11:19:58 Trace: [HiFiBerry Digi+] [Lossless, 24/48 MQA TIDAL FLAC => 24/48 MQA] [PLAYING @ 0:00] Mother (ultimate mix) - John Lennon
07/24 11:19:58 Trace: [prebuffer] ready 163200/480000 (34%) @ 0/335 sec
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [546] {"status":"Teardown"}
07/24 11:19:59 Trace: [streamingmediafile] [6] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDYwNjYxYmIwOTJjYTk1OTQ1YWE5NjM1ODE2MzkzN2ZfNjAubXA0/0.flac] [cachedbytes=0/-1] got length 31119641
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1017] {"status":"OutputMessage","message":{"signal_path":[{"type":"output","quality":"lossless","method":"digital"}]}}
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1017] {"clock_port":43797,"audio_port":0,"status":"Success","audio_port_tcp":36859}
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Idle => Prepared
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] synced to endpoint HiFiBerry Digi+ clock: realtime=83312769521870 rtt=500us offset=82314675521us delta=82314675521us
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1019]{"request":"stream","stream_id":46902529,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1019] {"status":"Buffering"}
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Prepared => Buffering
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry Digi+ as clock master
07/24 11:19:59 Debug: [raat/tcpaudiosource] connecting to 192.168.0.120:36859
07/24 11:19:59 Debug: [raat/tcpaudiosource] connected
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1019] {"status":"Ready"}
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Buffering => Ready
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1020]{"request":"update_artwork","mimetype":"image/jpeg"}
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1020] {"status":"Success"}
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] wait for ready in 0ms
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1021]{"request":"start","min_offset":59750000,"stream_sample":0}
07/24 11:19:59 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Ready => Playing
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1019] {"status":"Playing"}
07/24 11:19:59 Info: [HiFiBerry Digi+] [zoneplayer] Open result (Queueing): Result[Status=Success]
07/24 11:19:59 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1021] {"status":"Success","time":83312908316483}
07/24 11:19:59 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 48000/24/2  Quality=Lossless MQA 192000
    MQA Subtype=authentication LightState=off OriginalSampleRate=48000 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:19:59 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 48000/24/2  Quality=Lossless MQA 192000
    MQA Subtype=authentication LightState=valid OriginalSampleRate=192000 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:20:04 Trace: [HiFiBerry Digi+] [Lossless 87,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:04/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:09 Trace: [HiFiBerry Digi+] [Lossless 91,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:09/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:11 Info: [stats] 5577mb Virtual, 1214mb Physical, 477mb Managed, 0 Handles, 91 Threads
07/24 11:20:14 Trace: [HiFiBerry Digi+] [Lossless 92,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:14/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:19 Trace: [HiFiBerry Digi+] [Lossless 93,5x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:19/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:24 Trace: [HiFiBerry Digi+] [Lossless 94,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:25/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:26 Info: [stats] 5577mb Virtual, 1214mb Physical, 478mb Managed, 0 Handles, 91 Threads
07/24 11:20:29 Trace: [HiFiBerry Digi+] [Lossless 95,1x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:30/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:29 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83343384849093 rtt=500us offset=82314675849us delta=159us drift=327us in 30,615s (10,688ppm, 38,478ms/hr)
07/24 11:20:34 Trace: [HiFiBerry Digi+] [Lossless 95,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:35/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:39 Trace: [HiFiBerry Digi+] [Lossless 96,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:40/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:41 Info: [stats] 5577mb Virtual, 1214mb Physical, 478mb Managed, 0 Handles, 91 Threads
07/24 11:20:44 Trace: [HiFiBerry Digi+] [Lossless 96,5x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:45/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:49 Trace: [HiFiBerry Digi+] [Lossless 96,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:50/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:55 Trace: [HiFiBerry Digi+] [Lossless 96,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 0:55/5:36] Mother (ultimate mix) - John Lennon
07/24 11:20:56 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:21:00 Trace: [HiFiBerry Digi+] [Lossless 96,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:00/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:00 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83373992721889 rtt=500us offset=82314675721us delta=-245us drift=200us in 61,223s (3,267ppm, 11,761ms/hr)
07/24 11:21:05 Trace: [HiFiBerry Digi+] [Lossless 96,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:05/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:10 Trace: [HiFiBerry Digi+] [Lossless 96,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:11/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:11 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:21:15 Trace: [HiFiBerry Digi+] [Lossless 97,0x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:16/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:20 Trace: [HiFiBerry Digi+] [Lossless 97,0x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:21/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:25 Trace: [HiFiBerry Digi+] [Lossless 97,1x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:26/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:26 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:21:30 Trace: [HiFiBerry Digi+] [Lossless 97,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:31/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:31 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83404614032666 rtt=500us offset=82314676032us delta=65us drift=510us in 91,844s (5,562ppm, 20,022ms/hr)
07/24 11:21:35 Trace: [HiFiBerry Digi+] [Lossless 97,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:36/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:40 Trace: [HiFiBerry Digi+] [Lossless 97,3x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:41/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:41 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:21:45 Trace: [HiFiBerry Digi+] [Lossless 97,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:46/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:50 Trace: [HiFiBerry Digi+] [Lossless 97,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:51/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:55 Trace: [HiFiBerry Digi+] [Lossless 97,4x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 1:56/5:36] Mother (ultimate mix) - John Lennon
07/24 11:21:56 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:22:00 Trace: [HiFiBerry Digi+] [Lossless 97,4x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:01/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:01 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83435236708963 rtt=500us offset=82314676708us delta=411us drift=1187us in 122,466s (9,693ppm, 34,896ms/hr)
07/24 11:22:05 Trace: [HiFiBerry Digi+] [Lossless 97,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:06/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:10 Trace: [HiFiBerry Digi+] [Lossless 97,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:11/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:11 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:22:15 Trace: [HiFiBerry Digi+] [Lossless 97,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:16/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:20 Trace: [HiFiBerry Digi+] [Lossless 98,1x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:21/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:25 Trace: [HiFiBerry Digi+] [Lossless 98,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:26/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:26 Info: [stats] 5580mb Virtual, 1218mb Physical, 482mb Managed, 0 Handles, 91 Threads
07/24 11:22:28 Info: [brokerserver] Client disconnected: 192.168.0.101:50994
07/24 11:22:28 Trace: [push] restarting connection (Unable to read data from the transport connection: interrupted.)
07/24 11:22:28 Trace: [push] retrying connection in 14487ms
07/24 11:22:30 Trace: [HiFiBerry Digi+] [Lossless 98,3x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:31/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:32 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83465842718852 rtt=500us offset=82314676718us delta=-240us drift=1196us in 153,072s (7,820ppm, 28,151ms/hr)
07/24 11:22:35 Trace: [HiFiBerry Digi+] [Lossless 98,3x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:36/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:38 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
07/24 11:22:38 Trace: [push] connecting to 35.237.106.222:9204
07/24 11:22:38 Trace: [push] connected
07/24 11:22:40 Trace: [broker/accounts] [heartbeat] now=24.07.2021 7:22:40 nextauthrefresh=24.07.2021 8:02:47 nextmachineallocate=24.07.2021 11:02:40
07/24 11:22:40 Trace: [HiFiBerry Digi+] [Lossless 98,4x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:41/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:41 Info: [stats] 5580mb Virtual, 1218mb Physical, 483mb Managed, 0 Handles, 91 Threads
07/24 11:22:45 Trace: [HiFiBerry Digi+] [Lossless 98,5x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:46/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:50 Trace: [HiFiBerry Digi+] [Lossless 98,5x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:51/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:55 Trace: [HiFiBerry Digi+] [Lossless 98,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 2:56/5:36] Mother (ultimate mix) - John Lennon
07/24 11:22:56 Info: [stats] 5580mb Virtual, 1218mb Physical, 483mb Managed, 0 Handles, 91 Threads
07/24 11:23:01 Trace: [HiFiBerry Digi+] [Lossless 98,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:01/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:02 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83496455932203 rtt=500us offset=82314676932us delta=754us drift=1410us in 183,685s (7,678ppm, 27,641ms/hr)
07/24 11:23:06 Trace: [HiFiBerry Digi+] [Lossless 98,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:06/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:11 Info: [stats] 5580mb Virtual, 1218mb Physical, 483mb Managed, 0 Handles, 91 Threads
07/24 11:23:11 Trace: [HiFiBerry Digi+] [Lossless 98,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:12/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:16 Trace: [HiFiBerry Digi+] [Lossless 98,6x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:17/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:21 Trace: [HiFiBerry Digi+] [Lossless 98,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:22/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:26 Info: [stats] 5580mb Virtual, 1218mb Physical, 483mb Managed, 0 Handles, 91 Threads
07/24 11:23:26 Trace: [HiFiBerry Digi+] [Lossless 98,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:27/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:31 Trace: [HiFiBerry Digi+] [Lossless 98,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:32/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:33 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83527067556796 rtt=500us offset=82314676556us delta=83us drift=1034us in 214,297s (4,829ppm, 17,386ms/hr)
07/24 11:23:36 Trace: [HiFiBerry Digi+] [Lossless 98,7x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:37/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:41 Info: [stats] 5580mb Virtual, 1218mb Physical, 483mb Managed, 0 Handles, 90 Threads
07/24 11:23:41 Trace: [HiFiBerry Digi+] [Lossless 98,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:42/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:46 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:47/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:51 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:52/5:36] Mother (ultimate mix) - John Lennon
07/24 11:23:56 Info: [stats] 5584mb Virtual, 1222mb Physical, 487mb Managed, 0 Handles, 90 Threads
07/24 11:23:56 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 3:57/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:01 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:02/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:04 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83557680954149 rtt=500us offset=82314676954us delta=53us drift=1432us in 244,91s (5,848ppm, 21,053ms/hr)
07/24 11:24:06 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:07/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:11 Info: [stats] 5584mb Virtual, 1222mb Physical, 487mb Managed, 0 Handles, 90 Threads
07/24 11:24:11 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:12/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:16 Trace: [HiFiBerry Digi+] [Lossless 98,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:17/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:21 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:22/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:26 Info: [stats] 5584mb Virtual, 1222mb Physical, 487mb Managed, 0 Handles, 90 Threads
07/24 11:24:26 Trace: [HiFiBerry Digi+] [Lossless 98,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:27/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:31 Trace: [HiFiBerry Digi+] [Lossless 98,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:32/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:34 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83588303501907 rtt=500us offset=82314676501us delta=-420us drift=980us in 275,533s (3,557ppm, 12,805ms/hr)
07/24 11:24:36 Trace: [HiFiBerry Digi+] [Lossless 98,8x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:37/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:41 Info: [stats] 5598mb Virtual, 1233mb Physical, 498mb Managed, 0 Handles, 90 Threads
07/24 11:24:41 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:42/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:46 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:47/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:52 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:52/5:36] Mother (ultimate mix) - John Lennon
07/24 11:24:56 Info: [stats] 5613mb Virtual, 1244mb Physical, 509mb Managed, 0 Handles, 90 Threads
07/24 11:24:57 Trace: [HiFiBerry Digi+] [Lossless 99,0x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 4:58/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:02 Trace: [HiFiBerry Digi+] [Lossless 99,0x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 5:03/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:05 Trace: [HiFiBerry Digi+] [zoneplayer/raat] sync HiFiBerry Digi+: realtime=83618931298574 rtt=500us offset=82314677298us delta=489us drift=1776us in 306,16s (5,803ppm, 20,891ms/hr)
07/24 11:25:07 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 5:08/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:11 Info: [stats] 5613mb Virtual, 1244mb Physical, 509mb Managed, 0 Handles, 90 Threads
07/24 11:25:12 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 5:13/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:17 Trace: [HiFiBerry Digi+] [Lossless 98,9x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [100% buf] [PLAYING @ 5:18/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:19 Trace: [prebuffer] short read: 0 / 9600 fill=471680
07/24 11:25:22 Trace: [HiFiBerry Digi+] [Lossless 99,0x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [72% buf] [PLAYING @ 5:23/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:26 Info: [stats] 5613mb Virtual, 1244mb Physical, 509mb Managed, 0 Handles, 89 Threads
07/24 11:25:27 Trace: [HiFiBerry Digi+] [Lossless 99,1x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [22% buf] [PLAYING @ 5:28/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:32 Trace: [HiFiBerry Digi+] [Lossless 99,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [4% buf] [PLAYING @ 5:33/5:36] Mother (ultimate mix) - John Lennon
07/24 11:25:35 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Playing => Prepared
07/24 11:25:35 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1357]{"request":"end_stream"}
07/24 11:25:35 Info: [library] recorded play for profile 6125d203-fcc6-4179-aeb4-d2e688b24555: mediaid=168:0:174979689 metadataid= contentid=168:0:174979689 libraryid= isfromswim=False
07/24 11:25:35 Info: 

Logs:

Part 2
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 48000/24/2  Quality=Lossless MQA 192000
    MQA Subtype=authentication LightState=off OriginalSampleRate=48000 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:25:35 Info: MQA authentication
07/24 11:25:35 Info: [zone HiFiBerry Digi+] OnPlayFeedback StoppedEndOfMediaNatural
07/24 11:25:35 Debug: [zone HiFiBerry Digi+] _Advance
07/24 11:25:35 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1019] {"status":"Ended"}
07/24 11:25:35 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1357] {"status":"Success"}
07/24 11:25:35 Trace: [HiFiBerry Digi+] [Lossless 99,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [4% buf] [LOADING @ 0:00] Loaded Gun - The Dirty Knobs
07/24 11:25:35 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 48000/24/2  Quality=Lossless MQA 192000
    MQA Subtype=authentication LightState=off OriginalSampleRate=48000 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:25:36 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1359]{"request":"update_artwork","mimetype":"image/jpeg"}
07/24 11:25:36 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1359] {"status":"Success"}
07/24 11:25:39 Trace: [streamingmediafile] [6] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDYwNjYxYmIwOTJjYTk1OTQ1YWE5NjM1ODE2MzkzN2ZfNjAubXA0/0.flac] [cachedbytes=31119641/31119641] finished caching
07/24 11:25:41 Info: [stats] 5613mb Virtual, 1248mb Physical, 513mb Managed, 0 Handles, 88 Threads
07/24 11:25:48 Info: [brokerserver] Client connected: 192.168.0.101:51016
07/24 11:25:48 Trace: [raat] [sood] Refreshing device list
07/24 11:25:48 Trace: [raatserver] [sood] Refreshing device list
07/24 11:25:48 Trace: [raat] RAATServer discovered: RaatServer iPad-USER @ 192.168.0.101:9200
07/24 11:25:48 Info: [raatserver] GOT SERVER 540388f1-f109-4655-8665-53705b5c2136::98f86505-96e4-4dfb-9b9c-c7c6ad8360f1 @ 192.168.0.101:9200 iPad-USER PROTOVER=1 RAATVER=1.1.38 
07/24 11:25:48 Trace: [push] restarting connection (Unable to read data from the transport connection: interrupted.)
07/24 11:25:48 Trace: [raatserver] [RaatServer iPad-USER @ 192.168.0.101:9200] connecting (attempt 1)
07/24 11:25:48 Trace: [push] retrying connection in 44516ms
07/24 11:25:48 Trace: [HiFiBerry Digi+] [Lossless 99,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [4% buf] [LOADING @ 0:00] Loaded Gun - The Dirty Knobs
07/24 11:25:48 Trace: [raatserver] [RaatServer iPad-USER @ 192.168.0.101:9200] connected
07/24 11:25:48 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
07/24 11:25:48 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "auto_name": "iPad USER", "type": "ios", "is_system_output": true, "vendor": "Apple", "auto_enable": true, "name": "iPad USER", "model": "iPad7,11", "discovery_data": {"tcp_port": 51015, "model": "N/A", "unique_id": "66a4db1f-8bd6-c7ea-c572-6d6439f0ccda", "vendor": "N/A", "raat_version": "1.1.38", "version": "N/A", "protocol_version": "3"}, "config": {"external_config": {"is_private": true, "product_override": null}, "volume": {"device": "default", "type": "ios", "exclusive_mode": null}, "unique_id": "66a4db1f-8bd6-c7ea-c572-6d6439f0ccda", "output": {"device": "default", "type": "ios", "name": "iPad USER", "force_max_volume": false, "dsd_mode": "none"}}}]}
07/24 11:25:48 Info: [raatserver] GOT DEVICE 98f86505-96e4-4dfb-9b9c-c7c6ad8360f1::default Type=ios Name=iPad USER Vendor=Apple
07/24 11:25:48 Trace: [rnet/RnetJsonClient] SENT {"request":"enable_device","device_id":"default","subscription_id":"2"}
07/24 11:25:48 Info: [raatserver] [iPad USER] connecting (attempt 1)
07/24 11:25:48 Trace: [iPad USER] [raatclient] Initializing RAAT session
07/24 11:25:48 Trace: [iPad USER] [raatclient] Loading script raat.dat:base.lua as module base [1088 chars]
07/24 11:25:48 Trace: [iPad USER] [raatclient] Loading script raat.dat:dkjson.lua as module dkjson [22416 chars]
07/24 11:25:48 Trace: [iPad USER] [raatclient] Loading script raat.dat:protocol.lua as module protocol [2045 chars]
07/24 11:25:48 Trace: [iPad USER] [raatclient] Loading script raat.dat:roon_tcp.lua [43475 chars]
07/24 11:25:48 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success"}
07/24 11:25:48 Trace: [iPad USER] [raatclient] RAAT Session initialized in 224ms
07/24 11:25:48 Trace: [iPad USER] [raatclient] SENT [2]{"request":"info"}
07/24 11:25:48 Trace: [iPad USER] [raatclient] SENT [3]{"request":"set_client_type","client_type":"Roon"}
07/24 11:25:48 Trace: [iPad USER] [raatclient] GOT [2] {"info":{"raat_version":"1.1.38","unique_id":"66a4db1f-8bd6-c7ea-c572-6d6439f0ccda","protocol_version":"3","model":"N/A","version":"N/A","vendor":"N/A"},"source_selection":{"info":[],"is_supported":false},"status":"Success","platform":{"os":"ios","uname":{"release":"20.6.0","sysname":"Darwin","machine":"iPad7,11","version":"Darwin Kernel Version 20.6.0: Mon Jun 21 21:23:42 PDT 2021; root:xnu-7195.140.42~10/RELEASE_ARM64_T8010","nodename":"iPad-USER"}},"flags":{"has_write_chmap":true},"volume":{"info":{},"is_supported":true},"transport":{"info":{"config":{}},"is_supported":true,"is_update_artwork_supported":true,"is_update_status_supported":true},"output":{"supported_formats":"44100/24/2,48000/24/2,44100/16/2,48000/16/2","info":{"refresh_supported_formats_before_playback":true,"config":{"name":"iPad USER","device":"default","dsd_mode":"none","force_max_volume":false,"type":"ios"}}}}
07/24 11:25:48 Trace: [raatserver] [iPad USER] connected
07/24 11:25:48 Info: [transport] creating endpoint for raat device 66a4db1f-8bd6-c7ea-c572-6d6439f0ccda
07/24 11:25:48 Trace: [iPad USER] [raatclient] SENT [4]{"request":"subscribe_transport"}
07/24 11:25:48 Trace: [iPad USER] [raatclient] SENT [5]{"request":"subscribe_controls","controller_id":"1bc14457-e4b4-4e22-8617-0191cd064c1f"}
07/24 11:25:48 Trace: [dspengine] loaded DSP config {"version":3,"items":[{"type":"bs2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}],"headroom_adjustment":{"type":"headroom_adjustment"},"sample_rate_conversion":{"type":"sample_rate_conversion"},"auto_apply":false}
07/24 11:25:48 Trace: [transport/audeze] picking preset lowlatency 
07/24 11:25:48 Trace: [transport/audeze] picked null preset!
07/24 11:25:48 Trace: [iPad USER] [raatclient] GOT [3] {"status":"Success"}
07/24 11:25:48 Trace: [iPad USER] [raatclient] GOT [5] {"controls":{"volume":{"max":100.0,"min":0.0,"type":"number","step":1.0,"info":{},"value":24,"mute":false}},"status":"Success","action":"Changed"}
07/24 11:25:49 Trace: [zone iPad USER] Loading
07/24 11:25:49 Trace: [zone iPad USER] Suspend
07/24 11:25:49 Info: [zone iPad USER] Canceling Pending Sleep
07/24 11:25:49 Trace: [iPad USER] [zoneplayer/raat] Endpoint iPad USER Initial State: Idle
07/24 11:25:49 Info: [transport] created zone iPad USER
07/24 11:25:49 Info: [transport]     with endpoint iPad USER (23:1:66a4db1f-8bd6-c7ea-c572-6d6439f0ccda) [IsAvailable=True]
07/24 11:25:49 Debug: [easyhttp] [83] POST to https://discovery.roonlabs.net/1/query returned after 653 ms, status code: 200
07/24 11:25:49 Trace: [zone iPad USER] Loaded Queue=0 Tracks Swim=Inactive AutoSwim=False Loop=Disabled Shuffle=False
07/24 11:25:49 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=1bc14457-e4b4-4e22-8617-0191cd064c1f BrokerName='NUC8i5BEH' }
07/24 11:25:49 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800806 DisplayValue='1.8 (build 806) stable' Branch='stable' } }
07/24 11:25:49 Debug: [broker/filebrowser/drive] GetEntries: doing LsDir of  / , /root
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 1001771 bytes, 423 ops in 4727 ms)
07/24 11:25:49 Debug: [music/profilestats] Computed stats in 71ms, got 50 top albums, 50 top performers, 33 genres
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 17 ms (cumulative 1001771 bytes, 423 ops in 4744 ms)
07/24 11:25:49 Trace: [radio/query] performing channel query
07/24 11:25:49 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 5 ms (cumulative 1001771 bytes, 423 ops in 4749 ms)
07/24 11:25:49 Debug: [music/profilestats] Computed stats in 71ms, got 50 top albums, 50 top performers, 33 genres
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 16 ms (cumulative 1001771 bytes, 423 ops in 4765 ms)
07/24 11:25:49 Trace: [radio/query] performing channel query
07/24 11:25:49 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 1001771 bytes, 423 ops in 4768 ms)
07/24 11:25:49 Debug: [music/profilestats] Computed stats in 73ms, got 50 top albums, 50 top performers, 33 genres
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 17 ms (cumulative 1001771 bytes, 423 ops in 4785 ms)
07/24 11:25:49 Trace: [radio/query] performing channel query
07/24 11:25:49 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
07/24 11:25:49 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 1001771 bytes, 423 ops in 4788 ms)
07/24 11:25:49 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=AR&offset=0&limit=30 => Success
07/24 11:25:49 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=AR&offset=0&limit=30 => Success
07/24 11:25:49 Debug: [easyhttp] [84] GET to https://messaging.roonlabs.net/1/api/messages/d362fa3a-85f7-437d-ac61-05976055e5e8 returned after 616 ms, status code: 200
07/24 11:25:49 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=AR&offset=0&limit=30 => Success
07/24 11:25:49 Debug: [easyhttp] [86] GET to https://discover.roonlabs.net/1/mixes/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/mixes?localTime=2021-07-24T11:25:49.1128170&c=tidal-ar,qobuz-us returned after 634 ms, status code: 200
07/24 11:25:49 Debug: [easyhttp] [92] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=cf877b2d18d7e4c0fdbf930198326b28&request_ts=1627111549&&type=editor-picks returned after 583 ms, status code: 200
07/24 11:25:49 Debug: [easyhttp] [87] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 634 ms, status code: 304
07/24 11:25:50 Debug: [easyhttp] [100] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=cf877b2d18d7e4c0fdbf930198326b28&request_ts=1627111549&&type=editor-picks returned after 475 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [89] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/performers/recommended?profile=6125d203-fcc6-4179-aeb4-d2e688b24555&c=tidal-ar,qobuz-us returned after 695 ms, status code: 404
07/24 11:25:50 Debug: [easyhttp] [94] GET to https://discover.roonlabs.net/1/mixes/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/mixes?localTime=2021-07-24T11:25:49.1234910&c=tidal-ar,qobuz-us returned after 613 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [88] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/genres?c=tidal-ar,qobuz-us returned after 724 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [97] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/performers/recommended?profile=6125d203-fcc6-4179-aeb4-d2e688b24555&c=tidal-ar,qobuz-us returned after 566 ms, status code: 404
07/24 11:25:50 Debug: [easyhttp] [96] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/genres?c=tidal-ar,qobuz-us returned after 567 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [95] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 581 ms, status code: 304
07/24 11:25:50 Debug: [easyhttp] [99] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/featured/collaboration?c=tidal-ar,qobuz-us returned after 590 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [109] GET to https://metadata.roonlabs.net/1/albums/166:0:132367382?c=tidal-ar returned after 449 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [114] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478/relationships?c=tidal-ar,qobuz-us returned after 451 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [115] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478/refs?c=tidal-ar,qobuz-us returned after 453 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [116] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478/biographies?c=tidal-ar,qobuz-us returned after 454 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [113] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478?c=tidal-ar,qobuz-us returned after 455 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [118] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478/relationships?c=tidal-ar,qobuz-us returned after 455 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [112] GET to https://metadata.roonlabs.net/1/albums/166:0:132367382/reviews?c=tidal-ar returned after 457 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [111] GET to https://metadata.roonlabs.net/1/albums/166:0:132367382/credits?c=tidal-ar returned after 479 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [110] GET to https://metadata.roonlabs.net/1/albums/166:0:132367382/tracks?c=tidal-ar returned after 480 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [117] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478?c=tidal-ar,qobuz-us returned after 479 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [120] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478/biographies?c=tidal-ar,qobuz-us returned after 482 ms, status code: 200
07/24 11:25:50 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 1001771 bytes, 423 ops in 4789 ms)
07/24 11:25:50 Info: [metadata/albumdetails] Created album details screen for a600313332333637333832
07/24 11:25:50 Info: [metadata/albumdetails] 79004d5730303033333438373134,79004d5230303035313733303435,79004d5230303035313633323835,3e018c8f09243927744585097569766fed81,a600313332333637333832,c8007537307633686d636672313161
07/24 11:25:50 Trace: [library] finished with 1 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 1 changed objects
07/24 11:25:50 Debug: [music/profilestats] Computed stats in 75ms, got 50 top albums, 50 top performers, 33 genres
07/24 11:25:50 Trace: [dbperf] flush 0 bytes, 0 ops in 13 ms (cumulative 1001771 bytes, 423 ops in 4802 ms)
07/24 11:25:50 Trace: [library] endmutation in 157ms
07/24 11:25:50 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 1001771 bytes, 423 ops in 4803 ms)
07/24 11:25:50 Debug: [easyhttp] [108] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=cf877b2d18d7e4c0fdbf930198326b28&request_ts=1627111549&&type=editor-picks returned after 718 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [90] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/featured/coveredperformer?c=tidal-ar,qobuz-us returned after 1131 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [102] GET to https://discover.roonlabs.net/1/mixes/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/mixes?localTime=2021-07-24T11:25:49.1295630&c=tidal-ar,qobuz-us returned after 833 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [105] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/performers/recommended?profile=6125d203-fcc6-4179-aeb4-d2e688b24555&c=tidal-ar,qobuz-us returned after 758 ms, status code: 404
07/24 11:25:50 Debug: [easyhttp] [91] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/featured/collaboration?c=tidal-ar,qobuz-us returned after 1139 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [104] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/genres?c=tidal-ar,qobuz-us returned after 759 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [119] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003917478/refs?c=tidal-ar,qobuz-us returned after 755 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [93] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/albums/newv3?c=tidal-ar,qobuz-us returned after 1028 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [103] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 766 ms, status code: 304
07/24 11:25:50 Debug: [easyhttp] [85] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/albums/newv3?c=tidal-ar,qobuz-us returned after 1224 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [98] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/featured/coveredperformer?c=tidal-ar,qobuz-us returned after 948 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [107] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/featured/collaboration?c=tidal-ar,qobuz-us returned after 758 ms, status code: 200
07/24 11:25:50 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 1001771 bytes, 423 ops in 4804 ms)
07/24 11:25:50 Trace: [library] finished with 2 dirty performers 1 dirty places 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 2 changed objects
07/24 11:25:50 Trace: [library] endmutation in 20ms
07/24 11:25:50 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 1001771 bytes, 423 ops in 4805 ms)
07/24 11:25:50 Debug: [easyhttp] [106] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/featured/coveredperformer?c=tidal-ar,qobuz-us returned after 884 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [101] GET to https://discover.roonlabs.net/1/profiles/6125d203-fcc6-4179-aeb4-d2e688b24555/albums/newv3?c=tidal-ar,qobuz-us returned after 967 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [122] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-ar,qobuz-us returned after 596 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [121] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 596 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [125] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 413 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [123] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 414 ms, status code: 200
07/24 11:25:50 Debug: [easyhttp] [124] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-ar,qobuz-us returned after 414 ms, status code: 200
07/24 11:25:51 Debug: [easyhttp] [127] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-ar,qobuz-us returned after 591 ms, status code: 200
07/24 11:25:51 Debug: [easyhttp] [126] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 591 ms, status code: 200
07/24 11:25:51 Info: [library/albumdetails] found 2 streaming service alternates
07/24 11:25:51 Debug: [easyhttp] [128] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-ar,qobuz-us returned after 798 ms, status code: 200
07/24 11:25:56 Info: [stats] 5383mb Virtual, 1084mb Physical, 303mb Managed, 0 Handles, 87 Threads
07/24 11:25:58 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
07/24 11:25:58 Trace: [push] connecting to 34.138.211.184:9204
07/24 11:25:58 Trace: [push] connected
07/24 11:26:11 Info: [stats] 5383mb Virtual, 1084mb Physical, 303mb Managed, 0 Handles, 88 Threads
07/24 11:26:26 Info: [stats] 5383mb Virtual, 1084mb Physical, 303mb Managed, 0 Handles, 87 Threads
07/24 11:26:41 Info: [stats] 5383mb Virtual, 1084mb Physical, 303mb Managed, 0 Handles, 88 Threads
07/24 11:26:56 Info: [stats] 5383mb Virtual, 1084mb Physical, 307mb Managed, 0 Handles, 87 Threads
07/24 11:27:11 Info: [stats] 5383mb Virtual, 1084mb Physical, 307mb Managed, 0 Handles, 87 Threads

Logs:

Part 3
07/24 11:27:15 Warn: Error in web request https://api.tidal.com/v1/tracks/132367400/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM: NetworkError (The operation has timed out.)
07/24 11:27:15 Warn: [tidal/http] GET https://api.tidal.com/v1/tracks/132367400/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => NetworkError The operation has timed out.
07/24 11:27:15 Warn: [tidal/media] could not get playback info for 168:0:132367400: Result[Status=NetworkError]
07/24 11:27:15 Info: [zone HiFiBerry Digi+] OnPlayFeedback StoppedEndOfMediaUnnatural
07/24 11:27:15 Debug: [zone HiFiBerry Digi+] _Advance
07/24 11:27:15 Trace: [HiFiBerry Digi+] [Lossless 99,2x, 24/48 MQA TIDAL FLAC => 24/48 MQA] [4% buf] [LOADING @ 0:00] Age of Machine - Greta Van Fleet
07/24 11:27:15 Warn: Error in web request https://api.tidal.com/v1/tracks/163851473/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM: NetworkError (The operation has timed out.)
07/24 11:27:15 Warn: [tidal/http] GET https://api.tidal.com/v1/tracks/163851473/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => NetworkError The operation has timed out.
07/24 11:27:15 Warn: [tidal/media] could not get playback info for 168:0:163851473: Result[Status=NetworkError]
07/24 11:27:15 Debug: [easyhttp] [142] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630/biographies?c=tidal-ar,qobuz-us returned after 129 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [130] GET to https://metadata.roonlabs.net/1/tracks/168:0:163851473/lyrics returned after 147 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [131] GET to https://metadata.roonlabs.net/1/albums/166:0:163851472?c=tidal-ar returned after 146 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [134] GET to https://metadata.roonlabs.net/1/albums/166:0:163851472/reviews?c=tidal-ar returned after 146 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [135] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630?c=tidal-ar,qobuz-us returned after 146 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [132] GET to https://metadata.roonlabs.net/1/albums/166:0:163851472/tracks?c=tidal-ar returned after 148 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [137] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630/refs?c=tidal-ar,qobuz-us returned after 146 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [136] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630/relationships?c=tidal-ar,qobuz-us returned after 148 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [138] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630/biographies?c=tidal-ar,qobuz-us returned after 148 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [133] GET to https://metadata.roonlabs.net/1/albums/166:0:163851472/credits?c=tidal-ar returned after 170 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [139] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630?c=tidal-ar,qobuz-us returned after 167 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [140] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630/relationships?c=tidal-ar,qobuz-us returned after 167 ms, status code: 304
07/24 11:27:15 Info: [metadata/albumdetails] Created album details screen for a600313633383531343732
07/24 11:27:15 Info: [metadata/albumdetails] 79004d5730303033343537393633,79004d5230303035333139373132,3e01c32827a1a9afff4da5e1e22561b9d4ec,3e01b07d56be81ca56488a205324501c1536,a600313633383531343636,a600313633383531343732,c8006e7639796d6234333873326363,c800727132353576386d62776f6c61
07/24 11:27:15 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/163851473/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
07/24 11:27:15 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/162526580/playbackinfopostpaywall?countryCode=AR&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
07/24 11:27:15 Debug: [easyhttp] [141] GET to https://metadata.roonlabs.net/1/performers/122:0:MN0003612630/refs?c=tidal-ar,qobuz-us returned after 259 ms, status code: 304
07/24 11:27:15 Debug: [easyhttp] [144] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-ar,qobuz-us returned after 143 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [143] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 145 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [145] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 148 ms, status code: 200
07/24 11:27:15 Info: [library/albumdetails] found 4 streaming service alternates
07/24 11:27:15 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/162526580?countryCode=AR => Success
07/24 11:27:15 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/163851473?countryCode=AR => Success
07/24 11:27:15 Info: [HiFiBerry Digi+] [zoneplayer] Playing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac
07/24 11:27:15 Debug: [raat/tcpaudiosource] disconnecting
07/24 11:27:15 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
07/24 11:27:15 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
07/24 11:27:15 Info: [audio/env] [zoneplayer] All streams were disposed
07/24 11:27:15 Debug: [easyhttp] [146] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-ar,qobuz-us returned after 148 ms, status code: 200
07/24 11:27:15 Debug: [easyhttp] [147] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-ar,qobuz-us returned after 149 ms, status code: 200
07/24 11:27:15 Info: sleep 39ms after flush
07/24 11:27:15 Info: [HiFiBerry Digi+] [zoneplayer] Queueing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDQ5MjE3MWY4NDMxMjAwN2MzNGVmYTRiYWYzODQwNTlfNjAubXA0/0.flac
07/24 11:27:15 Trace: [streamingmediafile] [7] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac] [cachedbytes=0/-1] starting caching...
07/24 11:27:15 Trace: [streamingmediafile] [8] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDQ5MjE3MWY4NDMxMjAwN2MzNGVmYTRiYWYzODQwNTlfNjAubXA0/0.flac] [cachedbytes=0/-1] starting caching...
07/24 11:27:15 Trace: [streamingmediafile] [7] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac] [cachedbytes=0/-1] got length 85199967
07/24 11:27:15 Info: [HiFiBerry Digi+] [zoneplayer]     Open Result (Playing):Result[Status=Success]
07/24 11:27:15 Info: [HiFiBerry Digi+] [zoneplayer] Starting playback
07/24 11:27:15 Trace: [HiFiBerry Digi+] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=24, samplerate=44100, isdts=False, mqa=44100) => StreamFormat(channels=2, bitspersample=24, samplerate=44100, isdts=False, mqa=44100) streamid=46902530
07/24 11:27:15 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1363]{"request":"teardown"}
07/24 11:27:15 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Prepared => Idle
07/24 11:27:15 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1364]{"request":"setup","format":{"sample_type":"pcm","sample_rate":44100,"bits_per_sample":24,"channels":2,"sample_subtype":"mqa","mqa_original_sample_rate":44100}}
07/24 11:27:15 Info: [zone HiFiBerry Digi+] OnPlayFeedback Playing
07/24 11:27:15 Trace: [HiFiBerry Digi+] [Lossless, 24/44 MQA TIDAL FLAC => 24/44 MQA] [4% buf] [PLAYING @ 0:00] Age of Machine - Greta Van Fleet
07/24 11:27:15 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/413 sec
07/24 11:27:15 Trace: [streamingmediafile] [7] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac] [cachedbytes=709344/85199967] killing previous immediate read because 524288 != 0
07/24 11:27:15 Trace: [streamingmediafile] [7] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac] [cachedbytes=710764/85199967] starting immediate read at off=524288 length=85199967
07/24 11:27:15 Trace: [streamingmediafile] [8] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDQ5MjE3MWY4NDMxMjAwN2MzNGVmYTRiYWYzODQwNTlfNjAubXA0/0.flac] [cachedbytes=0/-1] got length 36545517
07/24 11:27:15 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1017] {"status":"Teardown"}
07/24 11:27:16 Trace: [streamingmediafile] [8] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDQ5MjE3MWY4NDMxMjAwN2MzNGVmYTRiYWYzODQwNTlfNjAubXA0/0.flac] [cachedbytes=262144/36545517] killing previous immediate read because 262144 != 0
07/24 11:27:16 Trace: [streamingmediafile] [8] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDQ5MjE3MWY4NDMxMjAwN2MzNGVmYTRiYWYzODQwNTlfNjAubXA0/0.flac] [cachedbytes=262144/36545517] starting immediate read at off=262144 length=36545517
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1364] {"status":"OutputMessage","message":{"signal_path":[{"type":"output","quality":"lossless","method":"digital"}]}}
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1364] {"clock_port":43797,"audio_port":0,"status":"Success","audio_port_tcp":36859}
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Idle => Prepared
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] synced to endpoint HiFiBerry Digi+ clock: realtime=91155900845640 rtt=0us offset=89720868845us delta=89720868845us
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1366]{"request":"stream","stream_id":46902530,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1366] {"status":"Buffering"}
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Prepared => Buffering
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry Digi+ as clock master
07/24 11:27:16 Debug: [raat/tcpaudiosource] connecting to 192.168.0.120:36859
07/24 11:27:16 Debug: [raat/tcpaudiosource] connected
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1366] {"status":"Ready"}
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Buffering => Ready
07/24 11:27:16 Trace: [streamingmediafile] [7] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac] [cachedbytes=2413344/85199967] killing previous immediate read because 2359296 != 557056
07/24 11:27:16 Trace: [streamingmediafile] [7] [http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZjNjZWFhNWQ4YzY0MTkwYjNmM2VlMGQyMDE5ZjcwYTRfNjAubXA0/0.flac] [cachedbytes=2413344/85199967] starting immediate read at off=2359296 length=85199967
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] wait for ready in 0ms
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1367]{"request":"start","min_offset":59637188,"stream_sample":0}
07/24 11:27:16 Trace: [HiFiBerry Digi+] [zoneplayer/raat] Endpoint HiFiBerry Digi+ State Changed: Ready => Playing
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1366] {"status":"Playing"}
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1367] {"status":"Success","time":91155991259513}
07/24 11:27:16 Info: [HiFiBerry Digi+] [zoneplayer] Open result (Queueing): Result[Status=Success]
07/24 11:27:16 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 44100/24/2  Quality=Lossless MQA 44100
    MQA Subtype=authentication LightState=off OriginalSampleRate=44100 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] SENT [1368]{"request":"update_artwork","mimetype":"image/jpeg"}
07/24 11:27:16 Trace: [HiFiBerry Digi+ @ 192.168.0.120:39625] [raatclient] GOT [1368] {"status":"Success"}
07/24 11:27:16 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 44100/24/2  Quality=Lossless MQA 44100
    MQA Subtype=authentication LightState=valid OriginalSampleRate=44100 OutputSampleRate= OutputBitsPerSample=
    Raat Device=HiFiBerry Digi+
    Output OutputType=Local_Digital Quality=Lossless SubType= Model=
------------------------------------------------------------
07/24 11:27:21 Trace: [HiFiBerry Digi+] [Lossless, 24/44 MQA TIDAL FLAC => 24/44 MQA] [100% buf] [PLAYING @ 0:04/6:54] Age of Machine - Greta Van Fleet
07/24 11:27:26 Info: [stats] 5378mb Virtual, 1070mb Physical, 268mb Managed, 0 Handles, 99 Threads
07/24 11:27:26 Trace: [HiFiBerry Digi+] [Lossless, 24/44 MQA TIDAL FLAC => 24/44 MQA] [100% buf] [PLAYING @ 0:10/6:54] Age of Machine - Greta Van Fleet
07/24 11:27:31 Trace: [HiFiBerry Digi+] [Lossless, 24/44 MQA TIDAL FLAC => 24/44 MQA] [100% buf] [PLAYING @ 0:15/6:54] Age of Machine - Greta Van Fleet
07/24 11:27:36 Trace: [HiFiBerry Digi+] [Lossless, 24/44 MQA TIDAL FLAC => 24/44 MQA] [100% buf] [PLAYING @ 0:20/6:54] Age of Machine - Greta Van Fleet

Now I return Roon Core to Windows and will watch :face_with_monocle:

Hi, @support
This weekend I tried 6 different configurations for Roon:

  1. NUC + ROCK
  2. NUC + Linux Mint + Roon Server
  3. NUC + Ubuntu Server + Roon Server
  4. Fujitsu Laptop + Linux Mint + Roon Server
  5. Fujitsu Laptop + Window 10 Pro + Roon App
  6. Fujitsu Laptop + Window 10 Pro + Roon Server

What can I say, Windows is the less problematic system in my case.
Didn’t notice any skips, only a few slow loading tracks. But I don’t like Windows. And I don’t want to buy extra license for it to install on NUC, because I’m not sure that this will fix all issues.
ROCK is the worst, surprisingly and unfortunately.
Now I’m on Laptop + Linux Mint + Roon Server, but I made some tricks.

I read that it could be specific global (national) firewall issues.
Some Amazon IPs are blocked.
Some users can fix it by adding their own firewall rules and block these IPs locally on the router with “reject” parameter, not “drop”
So Roon can immediately switch to another.
But these IPs change from time to time.

So, what I did: I manually add one firewall rule to iptables on Linux Mint:
sudo iptables-legacy -A OUTPUT -p tcp -d 13.32.123.24 -j REJECT
+
sudo iptables-legacy-save
to save the rule

This is the exact problem IP address for now.
6 month ago that address was 65.9.57.52
(Maybe I should add this address to that reject rule too. Unsure)

I left Roon to play 24 hours playlist in the background and this evening I will check if there will be any skips. (8 hours it’s ok so far)
If it work, I will move to NUC + Linux Mint + Roon Server + this firewall rule.
This is not elegant and not universal solution, but it works so far. Until another IP will be blocked.

Anyway, it doesn’t explain why Qobuz in Roon and Tidal itself work fine.
I think the issue must be solved somehow inside Roon.
And I hope that my sitiation will help Roon to fix that uncertain issues with Tidal or ROCK or Linux Roon Server.

Don’t want to mark this post as a solution.

BTW, Roon Remote on Android sees multiple Cores:

And these cores multiply and multiply.

Roon Remote on iPad sees one core at the same time:

Where are you from, Serge?

Vladislav, I’m from Russia. Read it here :wink:

кажется, ЗАРАБОТАЛО! не спугнуть бы

1 Like

Windows or Linux?
If Linux, don’t forget to
sudo iptables-legacy-save
it will save the rule to work after reboot

add new Firewall Rule in my Mikrotik Router
chain - Forward
Dst address 13.32.123.24
Protocol TCP
Action Reject

1 Like

It looks like I have to buy a new Router :man_facepalming:
Mikrotiks are more flexible.

Hi, @support

My second setup:
Roon Core - Intel NUC 8i5BEH2 (16Gb RAM, 512Gb M.2 SSD) - ROCK Version 1.0 (build 227) stable, Roon Server Version 1.8 (build 814) stable
Roon endpoint - KEF LS50 Wireless
Network Router - ASUS RT-N65U
Core connected directly to router via ethernet cable
KEFs - via WiFi
DNS - 8.8.8.8

The same issues as I already described here and here right in time when I’m posting this

I can see in router log that ROCK and Roon Remote can’t acces a dozen of IPs with “TIME_WAIT” state
These IPs are:

13.32.123.23
13.32.123.95
13.32.123.6
13.32.123.2
35.231.208.158

and they were not blocked by national firewall unlike 13.32.123.24

I hope this will help to resolve many issues like this
and this
and this
and this
and many others

Hello @Serge_Tse ,

Thanks for the additional details here! Let me discuss your findings with the QA team. Can you please confirm - if you by any chance have a VPN or mobile hotspot, does the same issue occur? Or does the issue only occur on your home network?

No, I don’t have any VPN. As I said earlier when I block (reject) 13.32.123.24 address Roon starts to function more or less stable.
But I can do it only if I’m on my main setup. Now I use Microtik router instead of TP-Link and it makes possible to add that specific firewall rule:

On ASUS router (second setup) I can’t do it.

If it will be helpful, here are ping and traceroute results from ASUS:

PING 35.231.208.158 (35.231.208.158): 56 data bytes
64 bytes from 35.231.208.158: seq=0 ttl=107 time=139.543 ms
64 bytes from 35.231.208.158: seq=1 ttl=108 time=138.375 ms
64 bytes from 35.231.208.158: seq=2 ttl=107 time=138.365 ms
64 bytes from 35.231.208.158: seq=3 ttl=107 time=138.378 ms
64 bytes from 35.231.208.158: seq=4 ttl=107 time=138.789 ms

--- 35.231.208.158 ping statistics ---
5 packets transmitted, 5 packets received, 0% packet loss
round-trip min/avg/max = 138.365/138.690/139.543 ms
traceroute to 35.231.208.158 (35.231.208.158), 30 hops max, 38 byte packets
 1  95.67.224.1 (95.67.224.1)  4.870 ms  5.252 ms  5.820 ms
 2  213.59.233.194 (213.59.233.194)  4.165 ms  3.618 ms  10.063 ms
 3  87.226.181.89 (87.226.181.89)  18.072 ms  87.226.183.89 (87.226.183.89)  16.992 ms  17.208 ms
 4  5.143.253.245 (5.143.253.245)  16.775 ms  5.143.253.105 (5.143.253.105)  16.827 ms  5.143.253.245 (5.143.253.245)  17.558 ms
 5  108.170.250.130 (108.170.250.130)  62.226 ms  108.170.250.51 (108.170.250.51)  16.927 ms  108.170.250.113 (108.170.250.113)  17.242 ms
 6  142.250.238.12 (142.250.238.12)  34.494 ms  34.378 ms  142.251.51.218 (142.251.51.218)  35.351 ms
 7  142.251.51.217 (142.251.51.217)  49.799 ms  142.251.51.215 (142.251.51.215)  48.963 ms  209.85.248.7 (209.85.248.7)  48.314 ms
 8  *  142.251.71.163 (142.251.71.163)  129.559 ms  134.152 ms
 9  209.85.254.106 (209.85.254.106)  139.408 ms  142.250.209.130 (142.250.209.130)  138.892 ms  216.239.47.82 (216.239.47.82)  139.593 ms
10  209.85.253.71 (209.85.253.71)  139.389 ms  216.239.46.64 (216.239.46.64)  151.552 ms  138.091 ms
11  142.250.60.97 (142.250.60.97)  139.203 ms  172.253.76.155 (172.253.76.155)  139.525 ms  216.239.50.29 (216.239.50.29)  138.906 ms
12  *  *  *
13  *  *  *
14  *  *  *
15  *  *  *
16  *  *  *
17  *  *  *
18  *  *  *
19  *  *  *
20  *  *  *
21  *  158.208.231.35.bc.googleusercontent.com (35.231.208.158)  138.015 ms  138.465 ms

Hello @Serge_Tse ,

Thank you for those additional details, I spoke to the QA team and we are looking into your report. Can you please confirm my previously asked question? If you try to use a mobile Hotspot, do you still see the issue occur when connecting that way? Or does the issue only occur on your current ISP network? Thanks!

Hi Noris,

Still have no idea how can I check this.
I can’t connect ROCK to Wi-Fi
Unfortunately, in two different locations I have the same ISP.
But I will think about it.

1 Like