Feb 21 22:14:49 volumio volumio[3097]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.66 UA: Mozilla/5.0 (Linux; Android 12; SM-G970U1 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:14:49 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 22:14:49 volumio volumio[3097]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 21 22:14:49 volumio volumio[3097]: info: Received Get System Info Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 22:14:49 volumio volumio[3097]: info: Discovery: Getting this device information Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:14:49 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:14:49 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:14:49 volumio volumio[3097]: info: Listing playlists Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::volumioGetQueue Feb 21 22:14:49 volumio volumio[3097]: info: CoreStateMachine::getQueue Feb 21 22:14:49 volumio volumio[3097]: info: CorePlayQueue::getQueue Feb 21 22:14:49 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 22:15:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 21 22:15:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 21 22:15:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 21 22:15:10 volumio volumio[3097]: info: Preload queue cleared Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::ClearQueue Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::stop Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::stPlaybackTimer Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::updateTrackBlock Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::getTrackBlock Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::pushState Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioPushState Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::serviceStop Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::serviceStop Feb 21 22:15:10 volumio volumio[3097]: info: [1771708510784] ControllerTidal::stop Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 22:15:10 volumio volumio[3097]: info: ControllerMpd::stop Feb 21 22:15:10 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::clearPlayQueue Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::saveQueue Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioPushQueue Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::addQueueItems Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::addQueueItems Feb 21 22:15:10 volumio volumio[3097]: info: Preload queue cleared Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/193955591 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/193955591 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/311158336 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/311158336 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/334883029 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/334883029 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/19374038 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/19374038 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/369340952 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/369340952 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/181696807 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/181696807 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/123651195 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/123651195 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/224284066 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/224284066 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/372465709 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/372465709 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/3175180 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/3175180 in service tidal Feb 21 22:15:10 volumio volumio[3097]: info: Adding Item to queue: tidal://song/71038445 Feb 21 22:15:10 volumio volumio[3097]: info: Exploding uri tidal://song/71038445 in service tidal Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.791+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" state=STATUS_STOPPED positionMs=0 volume=90 Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.792+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" id=tidal://song/68710445 title="You Really Got Me" Feb 21 22:15:10 volumio volumio[3097]: info: Feb 21 22:15:10 volumio volumio[3097]: ---------------------------- MPD announces state update: player Feb 21 22:15:10 volumio volumio[3097]: info: sendMpdCommand stop took 74 milliseconds Feb 21 22:15:10 volumio volumio[3097]: info: ControllerMpd::getState Feb 21 22:15:10 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand status Feb 21 22:15:10 volumio volumio[3097]: info: sendMpdCommand status took 1 milliseconds Feb 21 22:15:10 volumio volumio[3097]: verbose: ControllerMpd::parseState Feb 21 22:15:10 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 22:15:10 volumio volumio[3097]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 22:15:10 volumio volumio[3097]: verbose: ControllerMpd::parseTrackInfo Feb 21 22:15:10 volumio volumio[3097]: info: ControllerMpd::pushState Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::servicePushState Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::pushState Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioPushState Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:10 volumio volumio[3097]: info: CorePlayQueue::getTrack 28 Feb 21 22:15:10 volumio volumio[3097]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1771712011~NDAyOGU0NDE2Yzc0OWRiMGE5NWE5MzllNGZkMjYyYzQ3OGZhZDM2Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjMjFiZTRmYjBlY2QyNjc5YjU1ZDQ4YjM2ZjBlZjU2M182MS5tcDQ/0.flac?token=1771712011~NDAyOGU0NDE2Yzc0OWRiMGE5NWE5MzllNGZkMjYyYzQ3OGZhZDM2Zg==","trackType":"tidal"} Feb 21 22:15:10 volumio volumio[3097]: verbose: CURRENT POSITION 28 Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::syncState stateService stop Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::syncState currentStatus stop Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::pushState Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioPushState Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:10 volumio volumio[3097]: info: No code Feb 21 22:15:10 volumio volumio[3097]: info: CoreStateMachine::pushState Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioPushState Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:15:10 volumio volumio[3097]: info: MRS: Pushing multiroomSync output Feb 21 22:15:10 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.876+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" state=STATUS_PLAYING positionMs=1016 volume=90 Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.878+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" state=STATUS_PLAYING positionMs=1016 volume=90 Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.878+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" state=STATUS_PLAYING positionMs=1016 volume=90 Feb 21 22:15:10 volumio volumio[3097]: info: ------------------------------ 20ms Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.881+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" id=tidal://song/3246793 title="Purple Haze" Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.881+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" id=tidal://song/3246793 title="Purple Haze" Feb 21 22:15:10 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:10.881+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" id=tidal://song/3246793 title="Purple Haze" Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 302 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 318 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 326 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 338 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 357 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 372 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 392 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 396 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 416 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 434 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 621 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::volumioPushQueue Feb 21 22:15:11 volumio volumio[3097]: info: CorePlayQueue::saveQueue Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::updateTrackBlock Feb 21 22:15:11 volumio volumio[3097]: info: CorePlayQueue::getTrackBlock Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::volumioPlay Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::play index 10 Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::addQueueItems Feb 21 22:15:11 volumio volumio[3097]: info: CorePlayQueue::addQueueItems Feb 21 22:15:11 volumio volumio[3097]: info: Preload queue cleared Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/369339436 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/369339436 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/212362790 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/212362790 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/366918325 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/366918325 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/301959512 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/301959512 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/106600736 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/106600736 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/117837176 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/117837176 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/2121512 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/2121512 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/70815736 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/70815736 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/369190617 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/369190617 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/37808471 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/37808471 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/259314308 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/259314308 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/218474391 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/218474391 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/106600734 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/106600734 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/2210774 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/2210774 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/19374032 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/19374032 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/142893239 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/142893239 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/85792276 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/85792276 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/322233171 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/322233171 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/94435515 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/94435515 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/83265698 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/83265698 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/189655341 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/189655341 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/131877642 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/131877642 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/3714468 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/3714468 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/73097470 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/73097470 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/4684014 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/4684014 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/101586101 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/101586101 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/106600732 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/106600732 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/133425661 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/133425661 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: Adding Item to queue: tidal://song/50319392 Feb 21 22:15:11 volumio volumio[3097]: info: Exploding uri tidal://song/50319392 in service tidal Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::stop Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::play index undefined Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 22:15:11 volumio volumio[3097]: info: CorePlayQueue::getTrack 10 Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::startPlaybackTimer Feb 21 22:15:11 volumio volumio[3097]: info: CorePlayQueue::getTrack 10 Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 22:15:11 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 21 22:15:11 volumio volumio[3097]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Feb 21 22:15:11 volumio volumio[3097]: info: [1771708511449] ControllerTidal::clearAddPlayTrack Feb 21 22:15:11 volumio volumio[3097]: info: Getting stream with soundQuality LOSSLESS Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 179 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 173 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 176 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 176 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 183 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 184 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 180 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 183 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 183 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 203 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 218 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 449 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 451 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 457 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 459 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 456 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 468 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 497 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 511 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: getStreamUrl took 510 milliseconds Feb 21 22:15:11 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 22:15:11 volumio volumio[3097]: info: sendMpdCommand stop took 0 milliseconds Feb 21 22:15:11 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 22:15:11 volumio volumio[3097]: info: Feb 21 22:15:11 volumio volumio[3097]: ---------------------------- MPD announces system playlist update Feb 21 22:15:11 volumio volumio[3097]: info: Ignoring MPD Status Update Feb 21 22:15:11 volumio volumio[3097]: info: sendMpdCommand clear took 0 milliseconds Feb 21 22:15:11 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjOTRlMjQyZjFmODc0Y2FkMTVlZjIwNjc5YzY0ZDg5OV82MS5tcDQ/0.flac?token=1771712111~OGU1MDE0NTdjNDJhZWUzYTBmZWRmZGM5YWFmNzgwNTA5MTg5ZGFmZQ==" Feb 21 22:15:11 volumio volumio[3097]: error: updateQueue error: null Feb 21 22:15:11 volumio volumio[3097]: info: Feb 21 22:15:11 volumio volumio[3097]: ---------------------------- MPD announces system playlist update Feb 21 22:15:11 volumio volumio[3097]: info: Ignoring MPD Status Update Feb 21 22:15:11 volumio volumio[3097]: info: ------------------------------ 1ms Feb 21 22:15:11 volumio volumio[3097]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjOTRlMjQyZjFmODc0Y2FkMTVlZjIwNjc5YzY0ZDg5OV82MS5tcDQ/0.flac?token=1771712111~OGU1MDE0NTdjNDJhZWUzYTBmZWRmZGM5YWFmNzgwNTA5MTg5ZGFmZQ==" took 1 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 22:15:11 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand play Feb 21 22:15:11 volumio volumio[3097]: info: ------------------------------ 1ms Feb 21 22:15:11 volumio volumio[3097]: info: sendMpdCommand play took 1 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 525 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 531 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 533 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 536 milliseconds Feb 21 22:15:11 volumio volumio[3097]: info: explodeTIDALUri took 545 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: explodeTIDALUri took 570 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: Feb 21 22:15:12 volumio volumio[3097]: ---------------------------- MPD announces state update: player Feb 21 22:15:12 volumio volumio[3097]: info: ControllerMpd::getState Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand status Feb 21 22:15:12 volumio volumio[3097]: info: Feb 21 22:15:12 volumio volumio[3097]: ---------------------------- MPD announces state update: player Feb 21 22:15:12 volumio volumio[3097]: info: ControllerMpd::getState Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand status Feb 21 22:15:12 volumio volumio[3097]: info: sendMpdCommand status took 107 milliseconds Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::parseState Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 22:15:12 volumio volumio[3097]: info: explodeTIDALUri took 687 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: explodeTIDALUri took 690 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: explodeTIDALUri took 691 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: explodeTIDALUri took 698 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::volumioPushQueue Feb 21 22:15:12 volumio volumio[3097]: info: CorePlayQueue::saveQueue Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::updateTrackBlock Feb 21 22:15:12 volumio volumio[3097]: info: CorePlayQueue::getTrackBlock Feb 21 22:15:12 volumio volumio[3097]: info: sendMpdCommand status took 35 milliseconds Feb 21 22:15:12 volumio volumio[3097]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::parseState Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::parseTrackInfo Feb 21 22:15:12 volumio volumio[3097]: info: ControllerMpd::pushState Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::servicePushState Feb 21 22:15:12 volumio volumio[3097]: info: CorePlayQueue::getTrack 10 Feb 21 22:15:12 volumio volumio[3097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"498 Kbps","isStreaming":false,"title":"0.flac?token=1771712111~OGU1MDE0NTdjNDJhZWUzYTBmZWRmZGM5YWFmNzgwNTA5MTg5ZGFmZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjOTRlMjQyZjFmODc0Y2FkMTVlZjIwNjc5YzY0ZDg5OV82MS5tcDQ/0.flac?token=1771712111~OGU1MDE0NTdjNDJhZWUzYTBmZWRmZGM5YWFmNzgwNTA5MTg5ZGFmZQ==","trackType":"tidal"} Feb 21 22:15:12 volumio volumio[3097]: verbose: CURRENT POSITION 10 Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::syncState stateService play Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::syncState currentStatus stop Feb 21 22:15:12 volumio volumio[3097]: info: ------------------------------ 152ms Feb 21 22:15:12 volumio volumio[3097]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 21 22:15:12 volumio volumio[3097]: verbose: ControllerMpd::parseTrackInfo Feb 21 22:15:12 volumio volumio[3097]: info: ControllerMpd::pushState Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::servicePushState Feb 21 22:15:12 volumio volumio[3097]: info: CorePlayQueue::getTrack 10 Feb 21 22:15:12 volumio volumio[3097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1329,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"897 Kbps","isStreaming":false,"title":"0.flac?token=1771712111~OGU1MDE0NTdjNDJhZWUzYTBmZWRmZGM5YWFmNzgwNTA5MTg5ZGFmZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjOTRlMjQyZjFmODc0Y2FkMTVlZjIwNjc5YzY0ZDg5OV82MS5tcDQ/0.flac?token=1771712111~OGU1MDE0NTdjNDJhZWUzYTBmZWRmZGM5YWFmNzgwNTA5MTg5ZGFmZQ==","trackType":"tidal"} Feb 21 22:15:12 volumio volumio[3097]: verbose: CURRENT POSITION 10 Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::syncState stateService play Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::syncState currentStatus play Feb 21 22:15:12 volumio volumio[3097]: info: Received an update from plugin. extracting info from payload Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::pushState Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::volumioPushState Feb 21 22:15:12 volumio volumio[3097]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:15:12 volumio volumio[3097]: info: MRS: Pushing multiroomSync output Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:12 volumio volumio[3097]: info: CoreStateMachine::pushState Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::volumioPushState Feb 21 22:15:12 volumio volumio[3097]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:15:12 volumio volumio[3097]: info: MRS: Pushing multiroomSync output Feb 21 22:15:12 volumio volumio[3097]: info: CoreCommandRouter::volumioGetState Feb 21 22:15:12 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:12.203+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" state=STATUS_PLAYING positionMs=1215 volume=90 Feb 21 22:15:12 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:12.204+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" id=tidal://song/71038445 title=Trøllabundin Feb 21 22:15:12 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:12.206+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" state=STATUS_PLAYING positionMs=1215 volume=90 Feb 21 22:15:12 volumio volumio5-onboarding[1490]: time=2026-02-21T22:15:12.206+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:40012 @ 0x1d3a630" id=tidal://song/71038445 title=Trøllabundin Feb 21 22:15:12 volumio volumio[3097]: info: ------------------------------ 86ms Feb 21 22:15:13 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:13 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:13 volumio volumio[3097]: info: searchTIDALUri took 465 milliseconds Feb 21 22:15:13 volumio volumio[3097]: info: search took 465 milliseconds Feb 21 22:15:13 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::volumioGetQueue Feb 21 22:15:13 volumio volumio[3097]: info: CoreStateMachine::getQueue Feb 21 22:15:13 volumio volumio[3097]: info: CorePlayQueue::getQueue Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::volumioAddQueueItems Feb 21 22:15:13 volumio volumio[3097]: info: CoreStateMachine::addQueueItems Feb 21 22:15:13 volumio volumio[3097]: info: CorePlayQueue::addQueueItems Feb 21 22:15:13 volumio volumio[3097]: info: Preload queue cleared Feb 21 22:15:13 volumio volumio[3097]: info: Adding Item to queue: tidal://song/367864740 Feb 21 22:15:13 volumio volumio[3097]: info: Exploding uri tidal://song/367864740 in service tidal Feb 21 22:15:13 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:13 volumio volumio[3097]: info: searchTIDALUri took 503 milliseconds Feb 21 22:15:13 volumio volumio[3097]: info: search took 503 milliseconds Feb 21 22:15:13 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::volumioGetQueue Feb 21 22:15:13 volumio volumio[3097]: info: CoreStateMachine::getQueue Feb 21 22:15:13 volumio volumio[3097]: info: CorePlayQueue::getQueue Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::volumioAddQueueItems Feb 21 22:15:13 volumio volumio[3097]: info: CoreStateMachine::addQueueItems Feb 21 22:15:13 volumio volumio[3097]: info: CorePlayQueue::addQueueItems Feb 21 22:15:13 volumio volumio[3097]: info: Preload queue cleared Feb 21 22:15:13 volumio volumio[3097]: info: Adding Item to queue: tidal://song/15048130 Feb 21 22:15:13 volumio volumio[3097]: info: Exploding uri tidal://song/15048130 in service tidal Feb 21 22:15:13 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:13 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:14 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:14 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:14 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:14 volumio volumio[3097]: info: All cached search sources collected Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , search Feb 21 22:15:14 volumio volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 21 22:15:14 volumio volumio[3097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 22:15:14 volumio volumio[3097]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 21 22:15:14 volumio volumio[3097]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 21 22:15:14 volumio volumio[3097]: at new NodeError (node:internal/errors:405:5) Feb 21 22:15:14 volumio volumio[3097]: at assert (node:internal/assert:14:11) Feb 21 22:15:14 volumio volumio[3097]: at internalConnectMultiple (node:net:1118:3) Feb 21 22:15:14 volumio volumio[3097]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 21 22:15:14 volumio volumio[3097]: at listOnTimeout (node:internal/timers:575:11) Feb 21 22:15:14 volumio volumio[3097]: at process.processTimers (node:internal/timers:514:7) { Feb 21 22:15:14 volumio volumio[3097]: code: 'ERR_INTERNAL_ASSERTION' Feb 21 22:15:14 volumio volumio[3097]: } Feb 21 22:15:14 volumio volumio[3097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 22:15:14 volumio sudo[19652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 22:14' Feb 21 22:15:14 volumio sudo[19652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"