-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Sat 2026-05-30 22:09:23 +08. --
May 30 22:08:16 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri
May 30 22:08:16 pecan-pi volumio[869]: info: browseTIDALUri took 579 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Preload queue cleared
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091854
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091855
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091856
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091857
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091858
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091859
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091860
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091861
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091862
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091863
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091864
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091865
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091866
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091867
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091868
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091869
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091870
May 30 22:08:17 pecan-pi volumio[869]: info: Preloading song: tidal://song/121091871
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091854 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091855 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: No valid Plugin REST Endpoint: getSimilarAlbums
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091856 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091857 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091858 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091859 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091860 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 310 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091861 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 308 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091862 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 325 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 289 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091863 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 301 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091864 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091865 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 311 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 288 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091866 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091867 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 315 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091868 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 315 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091869 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 324 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091870 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 315 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 281 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: Exploding uri tidal://song/121091871 in service tidal
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 304 milliseconds
May 30 22:08:17 pecan-pi volumio[869]: info: explodeTIDALUri took 283 milliseconds
May 30 22:08:18 pecan-pi volumio[869]: info: explodeTIDALUri took 340 milliseconds
May 30 22:08:18 pecan-pi volumio[869]: info: explodeTIDALUri took 323 milliseconds
May 30 22:08:18 pecan-pi volumio[869]: info: explodeTIDALUri took 277 milliseconds
May 30 22:08:18 pecan-pi volumio[869]: info: explodeTIDALUri took 319 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: Preload queue cleared
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::ClearQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::stop
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::stPlaybackTimer
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::updateTrackBlock
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrackBlock
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 1
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::serviceStop
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 1
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::serviceStop
May 30 22:08:20 pecan-pi volumio[869]: info: [1780150100706] ControllerTidal::stop
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd
May 30 22:08:20 pecan-pi volumio[869]: info: ControllerMpd::stop
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand stop
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::clearPlayQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::saveQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::addQueueItems
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::addQueueItems
May 30 22:08:20 pecan-pi volumio[869]: info: Preload queue cleared
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091854
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091854
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091855
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091855
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091856
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091856
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::saveQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::updateTrackBlock
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrackBlock
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPlay
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::play index 2
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::addQueueItems
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::addQueueItems
May 30 22:08:20 pecan-pi volumio[869]: info: Preload queue cleared
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091857
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091857
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091858
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091858
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091859
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091859
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091860
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091860
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091861
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091861
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091862
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091862
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091863
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091863
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091864
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091864
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091865
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091865
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091866
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091866
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091867
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091867
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091868
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091868
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091869
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091869
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091870
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091870
May 30 22:08:20 pecan-pi volumio[869]: info: Adding Item to queue: tidal://song/121091871
May 30 22:08:20 pecan-pi volumio[869]: info: Using cached record of: tidal://song/121091871
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::stop
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::saveQueue
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::play index undefined
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::updateTrackBlock
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrackBlock
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::startPlaybackTimer
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:20 pecan-pi volumio[869]: info: [1780150100821] ControllerTidal::clearAddPlayTrack
May 30 22:08:20 pecan-pi volumio[869]: info: Getting stream with soundQuality LOSSLESS
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand stop
May 30 22:08:20 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 0
May 30 22:08:20 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand stop took 131 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand stop took 16 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand clear
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:20 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:20 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces system playlist update
May 30 22:08:20 pecan-pi volumio[869]: info: Ignoring MPD Status Update
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand status took 13 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand clear took 11 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand status took 9 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand status took 6 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS"
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:20 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:20 pecan-pi volumio[869]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd
May 30 22:08:20 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:20 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:20 pecan-pi volumio[869]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 77ms
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 76ms
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces system playlist update
May 30 22:08:20 pecan-pi volumio[869]: info: Ignoring MPD Status Update
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces system playlist update
May 30 22:08:20 pecan-pi volumio[869]: info: Ignoring MPD Status Update
May 30 22:08:20 pecan-pi volumio[869]: error: updateQueue error: null
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces system playlist update
May 30 22:08:20 pecan-pi volumio[869]: info: Ignoring MPD Status Update
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 88ms
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand playlistinfo took 85 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS" took 86 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 17ms
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 14ms
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:20 pecan-pi volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd
May 30 22:08:20 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand play
May 30 22:08:20 pecan-pi volumio[869]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 111ms
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces system playlist update
May 30 22:08:20 pecan-pi volumio[869]: info: Ignoring MPD Status Update
May 30 22:08:20 pecan-pi volumio[869]: info:
May 30 22:08:20 pecan-pi volumio[869]: ---------------------------- MPD announces system playlist update
May 30 22:08:20 pecan-pi volumio[869]: info: Ignoring MPD Status Update
May 30 22:08:20 pecan-pi volumio[869]: STREAMING PROXY: Handling url /?data=tidal://song/121091856&soundQuality=LOSSLESS
May 30 22:08:20 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 0
May 30 22:08:20 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 0
May 30 22:08:20 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:20 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 38ms
May 30 22:08:20 pecan-pi volumio[869]: info: sendMpdCommand play took 30 milliseconds
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 26ms
May 30 22:08:20 pecan-pi volumio[869]: info: ------------------------------ 24ms
May 30 22:08:20 pecan-pi volumio[869]: info: Executing endpoint getStreamUrltidal
May 30 22:08:20 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI
May 30 22:08:21 pecan-pi volumio[869]: info: getStreamUrl took 358 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ4ZGI3NWZjYzUxN2I3MzdlODQxM2YwOGVlMTYyM2Q3ZC5tcDQ/0.flac?token=1780153701~NmVhYTBlY2EyYzYyNDQ0NzliYTAxZjM5ZjQwODllNGMxYmM2YzUxMw==
May 30 22:08:21 pecan-pi volumio[869]: STREAMING PROXY: Requesting upstream with range: none
May 30 22:08:21 pecan-pi volumio[869]: STREAMING PROXY: Upstream status code: 200
May 30 22:08:21 pecan-pi volumio[869]: info:
May 30 22:08:21 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:21 pecan-pi volumio[869]: info:
May 30 22:08:21 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:21 pecan-pi volumio[869]: info:
May 30 22:08:21 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:21 pecan-pi volumio[869]: info:
May 30 22:08:21 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand status took 12 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand status took 10 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:21 pecan-pi volumio[869]: info:
May 30 22:08:21 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand status took 16 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand status took 12 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand playlistinfo took 9 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand playlistinfo took 8 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand status took 5 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:21 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:21 pecan-pi volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"668 Kbps","isStreaming":false,"title":"121091856&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS","trackType":"tidal"}
May 30 22:08:21 pecan-pi volumio[869]: verbose: CURRENT POSITION 2
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState stateService play
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState currentStatus stop
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:21 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:21 pecan-pi volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"678 Kbps","isStreaming":false,"title":"121091856&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS","trackType":"tidal"}
May 30 22:08:21 pecan-pi volumio[869]: verbose: CURRENT POSITION 2
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState stateService play
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState currentStatus play
May 30 22:08:21 pecan-pi volumio[869]: info: Received an update from plugin. extracting info from payload
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: ------------------------------ 95ms
May 30 22:08:21 pecan-pi volumio[869]: info: ------------------------------ 107ms
May 30 22:08:21 pecan-pi volumio[869]: info:
May 30 22:08:21 pecan-pi volumio[869]: ---------------------------- MPD announces state update: player
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::getState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand status
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand playlistinfo took 95 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand playlistinfo took 92 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand status took 13 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseState
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:21 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:21 pecan-pi volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":777,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"773 Kbps","isStreaming":false,"title":"121091856&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS","trackType":"tidal"}
May 30 22:08:21 pecan-pi volumio[869]: verbose: CURRENT POSITION 2
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState stateService play
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState currentStatus play
May 30 22:08:21 pecan-pi volumio[869]: info: Received an update from plugin. extracting info from payload
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:21 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:21 pecan-pi volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"752 Kbps","isStreaming":false,"title":"121091856&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS","trackType":"tidal"}
May 30 22:08:21 pecan-pi volumio[869]: verbose: CURRENT POSITION 2
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState stateService play
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState currentStatus play
May 30 22:08:21 pecan-pi volumio[869]: info: Received an update from plugin. extracting info from payload
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:21 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:21 pecan-pi volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"752 Kbps","isStreaming":false,"title":"121091856&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS","trackType":"tidal"}
May 30 22:08:21 pecan-pi volumio[869]: verbose: CURRENT POSITION 2
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState stateService play
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState currentStatus play
May 30 22:08:21 pecan-pi volumio[869]: info: Received an update from plugin. extracting info from payload
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: ------------------------------ 439ms
May 30 22:08:21 pecan-pi volumio[869]: info: ------------------------------ 436ms
May 30 22:08:21 pecan-pi volumio[869]: info: ------------------------------ 428ms
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: info: sendMpdCommand playlistinfo took 365 milliseconds
May 30 22:08:21 pecan-pi volumio[869]: verbose: ControllerMpd::parseTrackInfo
May 30 22:08:21 pecan-pi volumio[869]: info: ControllerMpd::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::servicePushState
May 30 22:08:21 pecan-pi volumio[869]: info: CorePlayQueue::getTrack 2
May 30 22:08:21 pecan-pi volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"689 Kbps","isStreaming":false,"title":"121091856&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/121091856&soundQuality=LOSSLESS","trackType":"tidal"}
May 30 22:08:21 pecan-pi volumio[869]: verbose: CURRENT POSITION 2
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState stateService play
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::syncState currentStatus play
May 30 22:08:21 pecan-pi volumio[869]: info: Received an update from plugin. extracting info from payload
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreStateMachine::pushState
May 30 22:08:21 pecan-pi volumio[869]: info: CoreCommandRouter::volumioPushState
May 30 22:08:21 pecan-pi volumio[869]: info: ------------------------------ 457ms
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:21 pecan-pi volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
May 30 22:08:22 pecan-pi volumio[869]: {"status":"play","position":2,"title":"Para Machuchar Meu Coracao","artist":"Stan Getz","album":"Getz/Gilberto (Expanded Edition)","albumart":"https://resources.tidal.com/images/bd66c497/5551/448b/84d0/e02fcad8a3fd/640x640.jpg","uri":"tidal://song/121091856","trackType":"tidal","seek":939,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"bitrate":"689 Kbps","random":false,"repeat":true,"repeatSingle":false,"consume":true,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"tidal"}
May 30 22:08:22 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:24 pecan-pi volumio[869]: STREAMING PROXY: Upstream closed. Received 30352561 bytes
May 30 22:08:24 pecan-pi volumio[869]: STREAMING PROXY: Client closed connection
May 30 22:08:27 pecan-pi go-librespot[1516]: time="2026-05-30T22:08:27+08:00" level=trace msg="received accesspoint ping"
May 30 22:08:27 pecan-pi go-librespot[1516]: time="2026-05-30T22:08:27+08:00" level=trace msg="received accesspoint pong ack"
May 30 22:08:27 pecan-pi go-librespot[1516]: time="2026-05-30T22:08:27+08:00" level=trace msg="sent dealer ping"
May 30 22:08:27 pecan-pi go-librespot[1516]: time="2026-05-30T22:08:27+08:00" level=trace msg="received dealer pong"
May 30 22:08:43 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri
May 30 22:08:43 pecan-pi volumio[869]: info: browseTIDALUri took 592 milliseconds
May 30 22:08:43 pecan-pi volumio[869]: info: Preload queue cleared
May 30 22:08:43 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313106
May 30 22:08:43 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313107
May 30 22:08:43 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313108
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313109
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313110
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313111
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313112
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313113
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313114
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313115
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313116
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313117
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313118
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313119
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313120
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313121
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313122
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313123
May 30 22:08:44 pecan-pi volumio[869]: info: Preloading song: tidal://song/55313124
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313106 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313107 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313108 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: No valid Plugin REST Endpoint: getSimilarAlbums
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313109 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313110 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313111 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 298 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313112 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313113 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 319 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313114 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313115 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 345 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 312 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 289 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313116 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313117 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 305 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 281 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313118 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313119 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 331 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 304 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313120 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313121 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 309 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 304 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313122 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313123 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 324 milliseconds
May 30 22:08:44 pecan-pi volumio[869]: info: Exploding uri tidal://song/55313124 in service tidal
May 30 22:08:44 pecan-pi volumio[869]: info: explodeTIDALUri took 308 milliseconds
May 30 22:08:45 pecan-pi volumio[869]: info: explodeTIDALUri took 290 milliseconds
May 30 22:08:45 pecan-pi volumio[869]: info: explodeTIDALUri took 287 milliseconds
May 30 22:08:45 pecan-pi volumio[869]: info: explodeTIDALUri took 274 milliseconds
May 30 22:08:45 pecan-pi volumio[869]: info: explodeTIDALUri took 310 milliseconds
May 30 22:08:45 pecan-pi volumio[869]: info: explodeTIDALUri took 281 milliseconds
May 30 22:08:45 pecan-pi volumio[869]: info: explodeTIDALUri took 741 milliseconds
May 30 22:08:46 pecan-pi volumiologrotate[614]: ls: cannot access '/var/log/samba/log.wb-PECAN': No such file or directory
May 30 22:08:46 pecan-pi volumiologrotate[614]: ls: cannot access 'PI+': No such file or directory
May 30 22:08:52 pecan-pi volumio[869]: {"status":"play","position":2,"title":"Para Machuchar Meu Coracao","artist":"Stan Getz","album":"Getz/Gilberto (Expanded Edition)","albumart":"https://resources.tidal.com/images/bd66c497/5551/448b/84d0/e02fcad8a3fd/640x640.jpg","uri":"tidal://song/121091856","trackType":"tidal","seek":939,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"bitrate":"689 Kbps","random":false,"repeat":true,"repeatSingle":false,"consume":true,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"tidal"}
May 30 22:08:52 pecan-pi volumio[869]: ACTIVITY STATUS CHANGED TO: 1
May 30 22:08:57 pecan-pi go-librespot[1516]: time="2026-05-30T22:08:57+08:00" level=trace msg="sent dealer ping"
May 30 22:08:57 pecan-pi go-librespot[1516]: time="2026-05-30T22:08:57+08:00" level=trace msg="received dealer pong"
May 30 22:09:21 pecan-pi volumio[869]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch
May 30 22:09:21 pecan-pi volumio[869]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 30 22:09:21 pecan-pi volumio[869]: TypeError: Cannot read property 'then' of undefined
May 30 22:09:21 pecan-pi volumio[869]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20)
May 30 22:09:21 pecan-pi volumio[869]: at Socket.emit (events.js:315:20)
May 30 22:09:21 pecan-pi volumio[869]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
May 30 22:09:21 pecan-pi volumio[869]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
May 30 22:09:21 pecan-pi volumio[869]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 30 22:09:23 pecan-pi sudo[2078]: volumio : unable to resolve host pecan-pi
May 30 22:09:23 pecan-pi sudo[2078]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ;
May 30 22:09:23 pecan-pi sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-30 22:08
May 30 22:09:23 pecan-pi sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="3dada8b1e619a5feb94867e0865ace17474d7bce"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="orchard"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 11:40:31 AM CET"
VOLUMIO_VERSION="3.912"
VOLUMIO_HARDWARE="pi-kiosk"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_VENDOR_MODEL="Orchard Audio PecanPi+ Streamer Premium"
VOLUMIO_VENDOR="Orchard Audio"
VOLUMIO_MODEL="PecanPi+ Streamer Premium"
VOLUMIO_HASH="3139c1501464197c1c80bce80492e4a3"