Roon Core Machine
Windows 10 machine
|Processor|Intel(R) Core™ i5-4690K CPU @ 3.50GHz 3.50 GHz|
|Installed RAM|16.0 GB|
|System type|64-bit operating system, x64-based processor|
Networking Gear & Setup Details
RoonCore on LAN wired directly on router. 550 MB up&down.
Connected Audio Devices
Chromecasts
Two google homes and one CCA
MacOS Laptop
Windows PC (on same machine as rooncore)
Android Device
Number of Tracks in Library
11 000
Description of Issue
Issue started yesterday and is reproduceable all the time with certain tracks.
Yesterday I closed my PC (roon server) the next day I reopened it, RoonServer running.
Tried playing an album from Mac OS. Album starts, notice some weird jumping around of the audio…
So let’s say the track was at 3:18 it would go backward to 3:16 then back to 3:20 in a 4 seconds period which is very annoying.
Thought the problem was my OS or something else so tried directly on my phone, my Chromecasts, directly on the Windows PC where the roon server is… still the problems persists. Thought TIDAL must be the issue but playing from there the problem was not happening.
In some case the issue is worse and the track skips once or twice and then stops completely this happens with the track I have posted the logs for below.
I tried reopening my PC, shutting down RoonServer, deleted the cache folder. Nothing changes issue persists the day after. Don’t really know what to do at this point.
I cannot confirm 100% but it seems to only be happening through TIDAL tracks.
Here are the logs for that specific track that stops completely:
01/15 13:48:11 Trace: [Windows 10] [HighQuality, 16/44 TIDAL FLAC => 16/44] [56% buf] [PLAYING @ 0:04/3:13] Wild - Spoon
01/15 13:48:15 Info: [library] saved recent ProfileId=62a4b151-fc6f-448e-9e5b-06b963d84051 Time=2022-01-15 6:48:13 PM DataType=album Type=long_nav MetadataId=9581990 ContentId=9581990 LibraryId= Text= Genre=
01/15 13:48:15 Debug: FTMSI-B ti/0153CD22 download status: AllBlocksDownloaded accessTimeout:True openFiles:1 prev:(FirstBlockRetrieved,True,1)
01/15 13:48:15 Trace: FTMSI-B 1 FileCache ti/C054FC57 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:True priorities: ('zoneplayer:4':1) --> bw limit:0kbps
01/15 13:48:15 Trace: FTMSI-B 2 FileCache ti/F97A6EF7 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:5':3) --> bw limit:0kbps
01/15 13:48:15 Trace: FTMSI-B 3 FileCache ti/0153CD22 dwStatus:AllBlocksDownloaded files:1 accessTimeOut:True priorities: ('zoneplayer:5':4) --> bw limit:51200kbps
01/15 13:48:15 Info: FTMSI-B ti/0153CD22: allocated bw changed from 51200 to 0 kbps
01/15 13:48:15 Info: FTMSI-B 1 FileCache ti/C054FC57 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:True priorities: ('zoneplayer:4':1) --> bw limit:0kbps
01/15 13:48:15 Info: FTMSI-B 2 FileCache ti/F97A6EF7 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:5':3) --> bw limit:0kbps
01/15 13:48:15 Info: FTMSI-B 3 FileCache ti/0153CD22 dwStatus:AllBlocksDownloaded files:1 accessTimeOut:True priorities: ('zoneplayer:5':4) --> bw limit:0kbps
01/15 13:48:15 Trace: FTMSI-B 1 FileCache ti/C054FC57 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:True priorities: ('zoneplayer:4':1) --> bw limit:0kbps
01/15 13:48:15 Trace: FTMSI-B 2 FileCache ti/F97A6EF7 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:5':3) --> bw limit:0kbps
01/15 13:48:15 Trace: FTMSI-B 3 FileCache ti/0153CD22 dwStatus:AllBlocksDownloaded files:1 accessTimeOut:True priorities: ('zoneplayer:5':4) --> bw limit:0kbps
01/15 13:48:16 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
01/15 13:48:16 Trace: [push] connecting to 35.237.82.78:9204
01/15 13:48:16 Trace: [push] connected
01/15 13:48:16 Trace: [Windows 10] [HighQuality, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 0:09/3:13] Wild - Spoon
01/15 13:48:17 Info: [stats] 5510mb Virtual, 452mb Physical, 188mb Managed, 1934 Handles, 86 Threads
01/15 13:48:20 Trace: [broker/accounts] [heartbeat] now=2022-01-15 6:48:19 PM nextauthrefresh=2022-01-15 6:53:19 PM nextmachineallocate=2022-01-15 9:53:19 PM
01/15 13:48:21 Trace: [Windows 10] [HighQuality, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 0:15/3:13] Wild - Spoon
01/15 13:48:22 Debug: FTMSI-B closed file for ti/0153CD22; open files:0
01/15 13:48:22 Trace: [Windows 10] [zoneplayer/raat] Endpoint System Output State Changed: Playing => Prepared
01/15 13:48:22 Debug: FTMSI-B ti/0153CD22 download status: AllBlocksDownloaded accessTimeout:True openFiles:0 prev:(AllBlocksDownloaded,True,1)
01/15 13:48:22 Trace: [System Output] [raatclient] SENT [15]{"request":"end_stream"}
01/15 13:48:22 Trace: FTMSI-B 1 FileCache ti/C054FC57 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:True priorities: ('zoneplayer:4':1) --> bw limit:0kbps
01/15 13:48:22 Trace: FTMSI-B 2 FileCache ti/F97A6EF7 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:5':3) --> bw limit:0kbps
01/15 13:48:22 Trace: FTMSI-B 3 FileCache ti/0153CD22 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:5':4) --> bw limit:0kbps
01/15 13:48:22 Info: [audio/env] [zoneplayer] All streams were disposed
01/15 13:48:22 Info: [library] recorded play for profile 62a4b151-fc6f-448e-9e5b-06b963d84051: mediaid=168:0:210205795 metadataid= contentid=168:0:210205795 libraryid= isfromswim=False
01/15 13:48:22 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Raat Device=System Output
Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality SubType= Model=System Output
------------------------------------------------------------
01/15 13:48:22 Trace: [System Output] [raatclient] GOT [13] {"status":"Ended"}
01/15 13:48:22 Info: [zone Windows 10] OnPlayFeedback StoppedEndOfMediaNatural
01/15 13:48:22 Debug: [zone Windows 10] _Advance
01/15 13:48:22 Trace: [System Output] [raatclient] GOT [15] {"status":"Success"}
01/15 13:48:22 Trace: [Windows 10] [HighQuality, 16/44 TIDAL FLAC => 16/44] [2% buf] [STOPPED @ 0:00]
01/15 13:48:27 Trace: [zone Windows 10] [zone] no playback for 5s, suspending to release audio device
01/15 13:48:27 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Inactive
Elements:
------------------------------------------------------------
01/15 13:48:27 Warn: inactive signal path :(
01/15 13:48:27 Trace: [zone Windows 10] Suspend
01/15 13:48:27 Trace: [zone Windows 10] Stop
01/15 13:48:27 Info: [zone Windows 10] OnPlayFeedback Stopped
01/15 13:48:27 Info: [zone Windows 10] Canceling Pending Sleep
01/15 13:48:27 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/15 13:48:27 Trace: [System Output] [raatclient] SENT [16]{"request":"teardown"}
01/15 13:48:27 Trace: [Windows 10] [zoneplayer/raat] Endpoint System Output State Changed: Prepared => Idle
01/15 13:48:27 Debug: [raat/tcpaudiosource] disconnecting
01/15 13:48:27 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/15 13:48:27 Trace: [System Output] [raatclient] GOT [12] {"status":"Teardown"}
01/15 13:48:27 Info: sleep 373ms after flush
01/15 13:48:32 Info: [stats] 5534mb Virtual, 449mb Physical, 190mb Managed, 1968 Handles, 92 Threads
01/15 13:48:47 Info: [stats] 5490mb Virtual, 449mb Physical, 189mb Managed, 1947 Handles, 81 Threads
01/15 13:49:02 Info: [stats] 5490mb Virtual, 449mb Physical, 191mb Managed, 1947 Handles, 81 Threads