May 30 18:16:02 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:08 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:14 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:26 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:32 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:38 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:46 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:16:54 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:00 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:06 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:12 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:18 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:21 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 30 18:17:21 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState May 30 18:17:21 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:22 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 30 18:17:22 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 30 18:17:24 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:17:25 volumio volumio[1325]: info: Retrieving Cloud Streaming UI May 30 18:17:25 volumio volumio[1325]: info: Getting Tidal Cloud Configuration May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:17:25 volumio volumio[1325]: info: Getting Qobuz Cloud Configuration May 30 18:17:25 volumio volumio[1325]: info: Asking plugin for UI Config May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:17:25 volumio volumio[1325]: info: Getting Spotify Cloud Configuration May 30 18:17:25 volumio volumio[1325]: info: Asking plugin for UI Config May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:17:25 volumio volumio[1325]: info: Saving Spotify Acccount May 30 18:17:25 volumio volumio[1325]: info: Got Tidal Cloud Configuration May 30 18:17:25 volumio volumio[1325]: info: Got it May 30 18:17:25 volumio volumio[1325]: info: Got it May 30 18:17:25 volumio volumio[1325]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 18:17:25 volumio volumio[1325]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::volumioGetBrowseSources May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::volumioGetBrowseSources May 30 18:17:25 volumio volumio[1325]: info: CoreCommandRouter::volumioGetBrowseSources May 30 18:17:26 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:26 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 30 18:17:30 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:30 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand update May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:33 volumio volumio[1325]: info: May 30 18:17:33 volumio volumio[1325]: ---------------------------- MPD announces state update: update May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand update took 2 milliseconds May 30 18:17:33 volumio volumio[1325]: info: ControllerMpd::getState May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:33 volumio volumio[1325]: info: May 30 18:17:33 volumio volumio[1325]: ---------------------------- MPD announces state update: update May 30 18:17:33 volumio volumio[1325]: info: ControllerMpd::getState May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand status took 2 milliseconds May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand status took 1 milliseconds May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand status took 2 milliseconds May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand status took 1 milliseconds May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:33 volumio volumio[1325]: info: Command Router : Notfying DB Updatetrue May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::Close All Modals sent May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:33 volumio volumio[1325]: info: Command Router : Notfying DB Updatetrue May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::Close All Modals sent May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand playlistinfo took 10 milliseconds May 30 18:17:33 volumio volumio[1325]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::parseTrackInfo May 30 18:17:33 volumio volumio[1325]: verbose: ControllerMpd::parseTrackInfo May 30 18:17:33 volumio volumio[1325]: info: ControllerMpd::pushState May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::servicePushState May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come Monday","artist":"Jimmy Buffett","album":"Ballads","uri":"http://192.168.68.50:10243/WMPNSSv4/2463911062/0_N182YWM0YzE2YS0zNDI2NjQ.mp3","trackType":"mp3"} May 30 18:17:33 volumio volumio[1325]: verbose: CURRENT POSITION 0 May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::syncState stateService stop May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::syncState currentStatus stop May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:33 volumio volumio[1325]: info: No code May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:33 volumio volumio[1325]: info: ControllerMpd::pushState May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::servicePushState May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come Monday","artist":"Jimmy Buffett","album":"Ballads","uri":"http://192.168.68.50:10243/WMPNSSv4/2463911062/0_N182YWM0YzE2YS0zNDI2NjQ.mp3","trackType":"mp3"} May 30 18:17:33 volumio volumio[1325]: verbose: CURRENT POSITION 0 May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::syncState stateService stop May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::syncState currentStatus stop May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:33 volumio volumio[1325]: info: No code May 30 18:17:33 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:33 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:33 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.887Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.888Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.888Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.888Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:33 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:33.890Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:33 volumio volumio[1325]: info: ------------------------------ 29ms May 30 18:17:33 volumio volumio[1325]: info: ------------------------------ 28ms May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:33 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:34 volumio volumio[1325]: info: May 30 18:17:34 volumio volumio[1325]: ---------------------------- MPD announces state update: update May 30 18:17:34 volumio volumio[1325]: info: ControllerMpd::getState May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:34 volumio volumio[1325]: info: May 30 18:17:34 volumio volumio[1325]: ---------------------------- MPD announces state update: update May 30 18:17:34 volumio volumio[1325]: info: ControllerMpd::getState May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand status May 30 18:17:34 volumio volumio[1325]: info: sendMpdCommand status took 1 milliseconds May 30 18:17:34 volumio volumio[1325]: info: sendMpdCommand status took 1 milliseconds May 30 18:17:34 volumio volumio[1325]: info: sendMpdCommand status took 1 milliseconds May 30 18:17:34 volumio volumio[1325]: info: sendMpdCommand status took 1 milliseconds May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:34 volumio volumio[1325]: info: Command Router : Notfying DB Updatefalse May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::Close All Modals sent May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:34 volumio volumio[1325]: info: Command Router : Notfying DB Updatefalse May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::Close All Modals sent May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::parseState May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 18:17:34 volumio volumio[1325]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 18:17:34 volumio volumio[1325]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::parseTrackInfo May 30 18:17:34 volumio volumio[1325]: verbose: ControllerMpd::parseTrackInfo May 30 18:17:34 volumio volumio[1325]: info: ControllerMpd::pushState May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::servicePushState May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come Monday","artist":"Jimmy Buffett","album":"Ballads","uri":"http://192.168.68.50:10243/WMPNSSv4/2463911062/0_N182YWM0YzE2YS0zNDI2NjQ.mp3","trackType":"mp3"} May 30 18:17:34 volumio volumio[1325]: verbose: CURRENT POSITION 0 May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::syncState stateService stop May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::syncState currentStatus stop May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:34 volumio volumio[1325]: info: No code May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:34 volumio volumio[1325]: info: ControllerMpd::pushState May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::servicePushState May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come Monday","artist":"Jimmy Buffett","album":"Ballads","uri":"http://192.168.68.50:10243/WMPNSSv4/2463911062/0_N182YWM0YzE2YS0zNDI2NjQ.mp3","trackType":"mp3"} May 30 18:17:34 volumio volumio[1325]: verbose: CURRENT POSITION 0 May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::syncState stateService stop May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::syncState currentStatus stop May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:34 volumio volumio[1325]: info: No code May 30 18:17:34 volumio volumio[1325]: info: CoreStateMachine::pushState May 30 18:17:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.022Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player state changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" state=STATUS_STOPPED positionMs=0 volume=0 May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.68.53:48900 @ 0x2ce3c20" id= title= May 30 18:17:34 volumio volumio5-onboarding[1680]: time=2026-05-31T00:17:34.023Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.53:48900 @ 0x2c01890" id= title= May 30 18:17:34 volumio volumio[1325]: info: ------------------------------ 19ms May 30 18:17:34 volumio volumio[1325]: info: ------------------------------ 20ms May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Volumio is not playing May 30 18:17:34 volumio volumio[1325]: info: FusionDsp - Clipped samples monitor stopped May 30 18:17:34 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:36 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:38 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:42 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:42 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:44 volumio volumio[1325]: info: Disabling MyMusic plugin upnp May 30 18:17:44 volumio sudo[13878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 30 18:17:44 volumio sudo[13878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 18:17:44 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... May 30 18:17:46 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:48 volumio wpa_supplicant[2532]: RRM: Ignoring radio measurement request: Not RRM network May 30 18:17:50 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 18:17:54 volumio volumio[1325]: info: Enabling MyMusic plugin upnp May 30 18:17:54 volumio volumio[1325]: info: Enabling plugin upnp May 30 18:17:54 volumio volumio[1325]: info: Loading plugin "upnp"... May 30 18:17:54 volumio volumio[1325]: info: [1780186674168] Starting Upmpd Daemon May 30 18:17:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 30 18:17:54 volumio volumio[1325]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:17:54 volumio volumio[1325]: Error: listen EADDRINUSE: address already in use :::6599 May 30 18:17:54 volumio volumio[1325]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) May 30 18:17:54 volumio volumio[1325]: at listenInCluster (node:net:1920:12) May 30 18:17:54 volumio volumio[1325]: at Server.listen (node:net:2008:7) May 30 18:17:54 volumio volumio[1325]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 30 18:17:54 volumio volumio[1325]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 30 18:17:54 volumio volumio[1325]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 30 18:17:54 volumio volumio[1325]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 30 18:17:54 volumio volumio[1325]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { May 30 18:17:54 volumio volumio[1325]: code: 'EADDRINUSE', May 30 18:17:54 volumio volumio[1325]: errno: -98, May 30 18:17:54 volumio volumio[1325]: syscall: 'listen', May 30 18:17:54 volumio volumio[1325]: address: '::', May 30 18:17:54 volumio volumio[1325]: port: 6599 May 30 18:17:54 volumio volumio[1325]: } May 30 18:17:54 volumio volumio[1325]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:17:54 volumio sudo[13909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 18:16' May 30 18:17:54 volumio sudo[13909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"