-- 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"