May 26 23:16:01 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:01 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:03 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:03 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:03 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:03 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:03 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:03 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:07 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:07 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:16:09 volumio volumio[83558]: info: Retrieving Cloud Streaming UI May 26 23:16:09 volumio volumio[83558]: info: Getting Tidal Cloud Configuration May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:16:09 volumio volumio[83558]: info: Getting Qobuz Cloud Configuration May 26 23:16:09 volumio volumio[83558]: info: Asking plugin for UI Config May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:16:09 volumio volumio[83558]: info: Getting Spotify Cloud Configuration May 26 23:16:09 volumio volumio[83558]: info: Asking plugin for UI Config May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:16:09 volumio volumio[83558]: info: Saving Spotify Acccount May 26 23:16:09 volumio volumio[83558]: info: Got it May 26 23:16:09 volumio volumio[83558]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 26 23:16:09 volumio volumio[83558]: info: Got Tidal Cloud Configuration May 26 23:16:09 volumio volumio[83558]: info: Got it May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::volumioGetBrowseSources May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::volumioGetBrowseSources May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::volumioGetBrowseSources May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 23:16:09 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 26 23:16:10 volumio volumio[83558]: error: Failed request for metavolumio API May 26 23:16:10 volumio volumio[83558]: error: Failed request for metavolumio API May 26 23:16:10 volumio volumio[83558]: error: Failed request for metavolumio API May 26 23:16:13 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 23:16:14 volumio go-librespot[83762]: time="2026-05-26T23:16:14+07:00" level=trace msg="sent dealer ping" May 26 23:16:14 volumio go-librespot[83762]: time="2026-05-26T23:16:14+07:00" level=trace msg="received dealer pong" May 26 23:16:17 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 26 23:16:17 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:17 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:17 volumio volumio[83558]: info: Listing playlists May 26 23:16:17 volumio volumio[83558]: info: Listing playlists May 26 23:16:22 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:22 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:24 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:24 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:24 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:24 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:24 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:24 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:26 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 26 23:16:26 volumio volumio[83558]: info: CURURI: music-library May 26 23:16:26 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:27 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 26 23:16:27 volumio volumio[83558]: info: CURURI: music-library/INTERNAL May 26 23:16:27 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:27 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:27 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:29 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 26 23:16:29 volumio volumio[83558]: info: CURURI: music-library/INTERNAL/Lossless May 26 23:16:29 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:31 volumio volumio[83558]: error: Failed request for metavolumio API May 26 23:16:31 volumio volumio[83558]: error: Failed request for metavolumio API May 26 23:16:31 volumio volumio[83558]: error: Failed request for metavolumio API May 26 23:16:37 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:37 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:37 volumio volumio[83558]: info: Listing playlists May 26 23:16:37 volumio volumio[83558]: info: Listing playlists May 26 23:16:40 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 26 23:16:40 volumio volumio[83558]: info: CURURI: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2 May 26 23:16:40 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:41 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 26 23:16:41 volumio volumio[83558]: info: CURURI: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2 May 26 23:16:41 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/01 Allegro Con Brio, Symphony No. 5.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/02 The Champions - ERA.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/03 Drum.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/04 Reborn.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/05 Ameno.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/07 Dance March.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/08 Toreo From Carmen.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/09 Autumn Leaves - Eva Cassidy.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/10 John McLaughlin, Al Di Meola & Paco De Lucia - Mediterranean Sundance-Rio Ancho.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/11 Next generation.wav May 26 23:16:41 volumio volumio[83558]: info: Preloading song: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/12 Mud In Your Eye.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/01 Allegro Con Brio, Symphony No. 5.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=California%20Guitar%20Trio/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F01%20Allegro%20Con%20Brio%2C%20Symphony%20No.%205.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/01 Allegro Con Brio, Symphony No. 5.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/02 The Champions - ERA.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Era/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F02%20The%20Champions%20-%20ERA.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/02 The Champions - ERA.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/03 Drum.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Charly%20Antolini/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F03%20Drum.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/03 Drum.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/04 Reborn.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Era%20/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F04%20Reborn.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/04 Reborn.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/05 Ameno.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Era%2C%20Eric%20Levi/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F05%20Ameno.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/05 Ameno.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Antonio%20Forcione/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F06%20Heartbeat.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/07 Dance March.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=London%20Cello%20Band%20/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F07%20Dance%20March.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/07 Dance March.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/08 Toreo From Carmen.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Georges%20Bizet%2C%20Rodion%20Shchedrin/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F08%20Toreo%20From%20Carmen.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/08 Toreo From Carmen.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/09 Autumn Leaves - Eva Cassidy.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Eva%20Cassidy/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F09%20Autumn%20Leaves%20-%20Eva%20Cassidy.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/09 Autumn Leaves - Eva Cassidy.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/10 John McLaughlin, Al Di Meola & Paco De Lucia - Mediterranean Sundance-Rio Ancho.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Al%20Di%20Meola%2C%20John%20McLaughlin%2C%20Paco%20De%20Lucia/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F10%20John%20McLaughlin%2C%20Al%20Di%20Meola%20%26%20Paco%20De%20Lucia%20-%20Mediterranean%20Sundance-Rio%20Ancho.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/10 John McLaughlin, Al Di Meola & Paco De Lucia - Mediterranean Sundance-Rio Ancho.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/11 Next generation.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Albrecht%20Riermeier/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F11%20Next%20generation.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/11 Next generation.wav May 26 23:16:41 volumio volumio[83558]: info: Exploding uri music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/12 Mud In Your Eye.wav in service mpd May 26 23:16:41 volumio volumio[83558]: info: ALBUMART /albumart?cacheid=411&web=Nils%20Lofgren/VA%20-%20Heartbeat%20-%20Vol2/extralarge&path=%2Fmnt%2FINTERNAL%2FLossless%2FVA%20-%20Heartbeat%20-%20Vol2%2FVA%20-%20Heartbeat%20-%20Vol2%2F12%20Mud%20In%20Your%20Eye.wav&metadata=false May 26 23:16:41 volumio volumio[83558]: info: URI /mnt/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/12 Mud In Your Eye.wav May 26 23:16:44 volumio go-librespot[83762]: time="2026-05-26T23:16:44+07:00" level=trace msg="sent dealer ping" May 26 23:16:44 volumio go-librespot[83762]: time="2026-05-26T23:16:44+07:00" level=trace msg="received dealer pong" May 26 23:16:47 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:47 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:55 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:55 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:16:55 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:16:55 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:16:55 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:55 volumio volumio[83558]: info: Adding Item to queue: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:55 volumio volumio[83558]: info: Using cached record of: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioPushQueue May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::saveQueue May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::updateTrackBlock May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrackBlock May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrack 0 May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioPlay May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::play index 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::stop May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::play index undefined May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::startPlaybackTimer May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioGetVisibleSources May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 23:16:56 volumio volumio[83558]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand stop May 26 23:16:56 volumio volumio[83558]: info: sendMpdCommand stop took 15 milliseconds May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand clear May 26 23:16:56 volumio volumio[83558]: info: May 26 23:16:56 volumio volumio[83558]: ---------------------------- MPD announces system playlist update May 26 23:16:56 volumio volumio[83558]: info: Ignoring MPD Status Update May 26 23:16:56 volumio volumio[83558]: info: sendMpdCommand clear took 154 milliseconds May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav" May 26 23:16:56 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:16:56 volumio volumio[83558]: info: Preload queue cleared May 26 23:16:56 volumio volumio[83558]: info: Adding Item to queue: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:56 volumio volumio[83558]: info: Using cached record of: music-library/INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioPushQueue May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::saveQueue May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::updateTrackBlock May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrackBlock May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioPlay May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::play index 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::stop May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::play index undefined May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreStateMachine::startPlaybackTimer May 26 23:16:56 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::volumioGetVisibleSources May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 23:16:56 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 23:16:56 volumio volumio[83558]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand stop May 26 23:16:56 volumio volumio[83558]: error: updateQueue error: null May 26 23:16:56 volumio volumio[83558]: info: May 26 23:16:56 volumio volumio[83558]: ---------------------------- MPD announces system playlist update May 26 23:16:56 volumio volumio[83558]: info: Ignoring MPD Status Update May 26 23:16:56 volumio volumio[83558]: info: ------------------------------ 337ms May 26 23:16:56 volumio volumio[83558]: info: sendMpdCommand add "INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav" took 334 milliseconds May 26 23:16:56 volumio volumio[83558]: info: sendMpdCommand stop took 96 milliseconds May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand play May 26 23:16:56 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand clear May 26 23:16:57 volumio volumio[83558]: info: May 26 23:16:57 volumio volumio[83558]: ---------------------------- MPD announces system playlist update May 26 23:16:57 volumio volumio[83558]: info: Ignoring MPD Status Update May 26 23:16:57 volumio volumio[83558]: info: May 26 23:16:57 volumio volumio[83558]: ---------------------------- MPD announces state update: player May 26 23:16:57 volumio volumio[83558]: info: ------------------------------ 287ms May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand play took 284 milliseconds May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand clear took 285 milliseconds May 26 23:16:57 volumio volumio[83558]: info: ControllerMpd::getState May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand status May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav" May 26 23:16:57 volumio volumio[83558]: error: updateQueue error: null May 26 23:16:57 volumio volumio[83558]: info: ------------------------------ 11ms May 26 23:16:57 volumio volumio[83558]: info: May 26 23:16:57 volumio volumio[83558]: ---------------------------- MPD announces system playlist update May 26 23:16:57 volumio volumio[83558]: info: Ignoring MPD Status Update May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand status took 11 milliseconds May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand add "INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav" took 11 milliseconds May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::parseState May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand play May 26 23:16:57 volumio volumio[83558]: info: ControllerMpd::pushState May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::servicePushState May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::pushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioPushState May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output update for this device May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 26 23:16:57 volumio volumio[83558]: verbose: CURRENT POSITION 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::syncState stateService stop May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::syncState currentStatus stop May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::pushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioPushState May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output update for this device May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: No code May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::pushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioPushState May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output update for this device May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: ------------------------------ 90ms May 26 23:16:57 volumio volumio[83558]: info: ------------------------------ 85ms May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand play took 81 milliseconds May 26 23:16:57 volumio volumio[83558]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 23:16:57 volumio volumio[83558]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 23:16:57 volumio volumio[83558]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 23:16:57 volumio volumio[83558]: info: May 26 23:16:57 volumio volumio[83558]: ---------------------------- MPD announces state update: player May 26 23:16:57 volumio volumio[83558]: info: ControllerMpd::getState May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand status May 26 23:16:57 volumio volumio[83558]: info: May 26 23:16:57 volumio volumio[83558]: ---------------------------- MPD announces state update: player May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand status took 61 milliseconds May 26 23:16:57 volumio volumio[83558]: info: ControllerMpd::getState May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand status May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::parseState May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand status took 4 milliseconds May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand playlistinfo took 4 milliseconds May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::parseState May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::parseTrackInfo May 26 23:16:57 volumio volumio[83558]: info: ControllerMpd::pushState May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::servicePushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":429,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Heartbeat","artist":"Antonio Forcione","album":"VA - Heartbeat - Vol2","uri":"INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav","trackType":"wav"} May 26 23:16:57 volumio volumio[83558]: verbose: CURRENT POSITION 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::syncState stateService play May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::syncState currentStatus stop May 26 23:16:57 volumio volumio[83558]: info: ------------------------------ 71ms May 26 23:16:57 volumio volumio[83558]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 23:16:57 volumio volumio[83558]: verbose: ControllerMpd::parseTrackInfo May 26 23:16:57 volumio volumio[83558]: info: ControllerMpd::pushState May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::servicePushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":429,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Heartbeat","artist":"Antonio Forcione","album":"VA - Heartbeat - Vol2","uri":"INTERNAL/Lossless/VA - Heartbeat - Vol2/VA - Heartbeat - Vol2/06 Heartbeat.wav","trackType":"wav"} May 26 23:16:57 volumio volumio[83558]: verbose: CURRENT POSITION 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::syncState stateService play May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::syncState currentStatus play May 26 23:16:57 volumio volumio[83558]: info: Received an update from plugin. extracting info from payload May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::pushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioPushState May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output update for this device May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreStateMachine::pushState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioPushState May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output update for this device May 26 23:16:57 volumio volumio[83558]: info: MRS: Pushing multiroomSync output May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: ------------------------------ 55ms May 26 23:16:57 volumio volumio[83558]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 23:16:57 volumio volumio[83558]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 23:16:57 volumio volumio[83558]: info: CoreCommandRouter::volumioGetState May 26 23:16:57 volumio volumio[83558]: info: CorePlayQueue::getTrack 2760 May 26 23:16:57 volumio volumio[83558]: info: Listing playlists May 26 23:16:57 volumio volumio[83558]: info: Listing playlists May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::volumioGetBrowseSources May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::volumioGetBrowseSources May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: All cached search sources collected May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:16:58 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:16:59 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:59 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:59 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:59 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:16:59 volumio volumio[83558]: info: Executing endpoint metavolumio May 26 23:16:59 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: All cached search sources collected May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: spop , search May 26 23:17:00 volumio volumio[83558]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3533 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3582 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/93854275 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/93854275 in service tidal May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3587 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3633 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3576 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3645 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/256310401 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/256310401 in service tidal May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3597 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3674 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/105779807 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/105779807 in service tidal May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3652 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3718 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/105780585 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/105780585 in service tidal May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3656 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3756 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/2407880 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/2407880 in service tidal May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3739 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3817 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/304035159 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/304035159 in service tidal May 26 23:17:02 volumio volumio[83558]: info: searchTIDALUri took 3792 milliseconds May 26 23:17:02 volumio volumio[83558]: info: search took 3851 milliseconds May 26 23:17:02 volumio volumio[83558]: info: All search sources collected, pushing search results May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioGetQueue May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::getQueue May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::getQueue May 26 23:17:02 volumio volumio[83558]: info: CoreCommandRouter::volumioAddQueueItems May 26 23:17:02 volumio volumio[83558]: info: CoreStateMachine::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: CorePlayQueue::addQueueItems May 26 23:17:02 volumio volumio[83558]: info: Preload queue cleared May 26 23:17:02 volumio volumio[83558]: info: Adding Item to queue: tidal://song/304035168 May 26 23:17:02 volumio volumio[83558]: info: Exploding uri tidal://song/304035168 in service tidal May 26 23:17:02 volumio volumio[83558]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 23:17:02 volumio volumio[83558]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. May 26 23:17:02 volumio volumio[83558]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues May 26 23:17:02 volumio volumio[83558]: at new NodeError (node:internal/errors:405:5) May 26 23:17:02 volumio volumio[83558]: at assert (node:internal/assert:14:11) May 26 23:17:02 volumio volumio[83558]: at internalConnectMultiple (node:net:1118:3) May 26 23:17:02 volumio volumio[83558]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) May 26 23:17:02 volumio volumio[83558]: at listOnTimeout (node:internal/timers:575:11) May 26 23:17:02 volumio volumio[83558]: at process.processTimers (node:internal/timers:514:7) { May 26 23:17:02 volumio volumio[83558]: code: 'ERR_INTERNAL_ASSERTION' May 26 23:17:02 volumio volumio[83558]: } May 26 23:17:02 volumio volumio[83558]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 23:17:02 volumio sudo[85237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 23:16' May 26 23:17:02 volumio sudo[85237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"