Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:07:00 volumioboss volumio[1243]: info: Retrieving Cloud Streaming UI Oct 16 00:07:00 volumioboss volumio[1243]: info: Getting Tidal Cloud Configuration Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:07:00 volumioboss volumio[1243]: info: Getting Qobuz Cloud Configuration Oct 16 00:07:00 volumioboss volumio[1243]: info: Asking plugin for UI Config Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:07:00 volumioboss volumio[1243]: info: Getting Spotify Cloud Configuration Oct 16 00:07:00 volumioboss volumio[1243]: info: Asking plugin for UI Config Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:07:00 volumioboss volumio[1243]: info: Saving Spotify Acccount Oct 16 00:07:00 volumioboss volumio[1243]: info: Got Tidal Cloud Configuration Oct 16 00:07:00 volumioboss volumio[1243]: info: Got it Oct 16 00:07:00 volumioboss volumio[1243]: info: Got it Oct 16 00:07:00 volumioboss volumio[1243]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:07:00 volumioboss volumio[1243]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:00 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 00:07:02 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:02 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:03 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 00:07:04 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:06 volumioboss volumio[1243]: info: Oct 16 00:07:06 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:07:06 volumioboss volumio[1243]: info: sendMpdCommand rescan took 11 milliseconds Oct 16 00:07:06 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:06 volumioboss volumio[1243]: info: Oct 16 00:07:06 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:07:06 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:06 volumioboss volumio[1243]: info: sendMpdCommand status took 33 milliseconds Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:06 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:07:06 volumioboss volumio[1243]: info: sendMpdCommand status took 130 milliseconds Oct 16 00:07:06 volumioboss volumio[1243]: info: sendMpdCommand status took 129 milliseconds Oct 16 00:07:06 volumioboss volumio[1243]: info: sendMpdCommand status took 129 milliseconds Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:06 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:07:06 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:06 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:07:06 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:06 volumioboss volumio[1243]: info: No code Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:06 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:07:06 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:06 volumioboss volumio[1243]: info: No code Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:06 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:06 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:06 volumioboss volumio[1243]: info: ------------------------------ 187ms Oct 16 00:07:06 volumioboss volumio[1243]: info: ------------------------------ 231ms Oct 16 00:07:06 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:06.972Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:06 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:06.972Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:06 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:06.972Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:06 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:06.972Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:06.992Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:06.992Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:07.019Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:07.019Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:07.021Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:07.022Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:07.022Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:07 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:07.024Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:07 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:07 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:08 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 00:07:11 volumioboss volumio[1243]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 16 00:07:11 volumioboss volumio[1243]: info: Received Get System Version Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 00:07:11 volumioboss volumio[1243]: info: Received Get System Info Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 00:07:11 volumioboss volumio[1243]: info: Discovery: Getting this device information Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 16 00:07:11 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 00:07:12 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:12 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:12 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:16 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:17 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:17 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:20 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:22 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:22 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:24 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:27 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:27 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:28 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:30 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:07:30 volumioboss volumio[1243]: info: Oct 16 00:07:30 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:30 volumioboss volumio[1243]: info: Oct 16 00:07:30 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:30 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:07:30 volumioboss volumio[1243]: info: Oct 16 00:07:30 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:30 volumioboss volumio[1243]: info: Oct 16 00:07:30 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:07:30 volumioboss volumio[1243]: info: sendMpdCommand status took 331 milliseconds Oct 16 00:07:30 volumioboss volumio[1243]: info: sendMpdCommand status took 330 milliseconds Oct 16 00:07:30 volumioboss volumio[1243]: info: sendMpdCommand status took 331 milliseconds Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:30 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: 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} Oct 16 00:07:30 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: No code Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: 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} Oct 16 00:07:30 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: No code Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.697Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.697Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.699Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.699Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.700Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.701Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.701Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.702Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.702Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.702Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.703Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.704Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio[1243]: info: ------------------------------ 557ms Oct 16 00:07:30 volumioboss volumio[1243]: info: ------------------------------ 558ms Oct 16 00:07:30 volumioboss volumio[1243]: info: sendMpdCommand status took 652 milliseconds Oct 16 00:07:30 volumioboss volumio[1243]: info: sendMpdCommand status took 651 milliseconds Oct 16 00:07:30 volumioboss volumio[1243]: info: sendMpdCommand status took 651 milliseconds Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:30 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:30 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: 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} Oct 16 00:07:30 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: No code Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: 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} Oct 16 00:07:30 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio[1243]: info: No code Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:07:30 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:30 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.853Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.854Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.854Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.854Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.855Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.856Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.856Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.857Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.857Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.858Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.859Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:07:30 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:07:30.859Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:07:30 volumioboss volumio[1243]: info: ------------------------------ 709ms Oct 16 00:07:30 volumioboss volumio[1243]: info: ------------------------------ 709ms Oct 16 00:07:32 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:32 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:32 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:36 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:37 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:37 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:40 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:07:42 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 16 00:07:42 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:07:42 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:42 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:45 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetQueue Oct 16 00:07:45 volumioboss volumio[1243]: info: CoreStateMachine::getQueue Oct 16 00:07:45 volumioboss volumio[1243]: info: CorePlayQueue::getQueue Oct 16 00:07:47 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:47 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:52 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:52 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:07:53 volumioboss volumio[1243]: Searching plugin music_service/mpd Oct 16 00:07:53 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 00:07:53 volumioboss volumio[1243]: info: All search sources collected, pushing search results Oct 16 00:07:54 volumioboss volumio[1243]: Searching plugin music_service/mpd Oct 16 00:07:54 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 00:07:54 volumioboss volumio[1243]: info: All search sources collected, pushing search results Oct 16 00:07:56 volumioboss volumio[1243]: Searching plugin music_service/mpd Oct 16 00:07:56 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 16 00:07:56 volumioboss volumio[1243]: info: All search sources collected, pushing search results Oct 16 00:07:57 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:07:57 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:02 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:02 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:04 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetState Oct 16 00:08:04 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:07 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:07 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:08:11 volumioboss volumio[1243]: info: Retrieving Cloud Streaming UI Oct 16 00:08:11 volumioboss volumio[1243]: info: Getting Tidal Cloud Configuration Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:08:11 volumioboss volumio[1243]: info: Getting Qobuz Cloud Configuration Oct 16 00:08:11 volumioboss volumio[1243]: info: Asking plugin for UI Config Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:08:11 volumioboss volumio[1243]: info: Getting Spotify Cloud Configuration Oct 16 00:08:11 volumioboss volumio[1243]: info: Asking plugin for UI Config Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:08:11 volumioboss volumio[1243]: info: Saving Spotify Acccount Oct 16 00:08:11 volumioboss volumio[1243]: info: Got Tidal Cloud Configuration Oct 16 00:08:11 volumioboss volumio[1243]: info: Got it Oct 16 00:08:11 volumioboss volumio[1243]: info: Got it Oct 16 00:08:11 volumioboss volumio[1243]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 00:08:11 volumioboss volumio[1243]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:11 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 00:08:12 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:12 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:15 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:15 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 16 00:08:15 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand update Oct 16 00:08:15 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:15 volumioboss volumio[1243]: info: Oct 16 00:08:15 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:15 volumioboss volumio[1243]: info: sendMpdCommand update took 4 milliseconds Oct 16 00:08:15 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:15 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:15 volumioboss volumio[1243]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:08:15 volumioboss volumio[1243]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:08:15 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:08:16 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: No code Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.034Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.034Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.037Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.037Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.039Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.040Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio[1243]: info: ------------------------------ 356ms Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: Oct 16 00:08:16 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:08:16 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: No code Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.113Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.114Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.116Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.116Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.120Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.120Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio[1243]: info: ------------------------------ 80ms Oct 16 00:08:16 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:08:16 volumioboss volumio[1243]: info: Oct 16 00:08:16 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: Oct 16 00:08:16 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:08:16 volumioboss volumio[1243]: info: Oct 16 00:08:16 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: Oct 16 00:08:16 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 242 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 235 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 236 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: 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} Oct 16 00:08:16 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: No code Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: 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} Oct 16 00:08:16 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: No code Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.462Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.462Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.463Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.464Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.465Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.466Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.466Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.466Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.467Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.467Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.469Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.469Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio[1243]: info: ------------------------------ 331ms Oct 16 00:08:16 volumioboss volumio[1243]: info: ------------------------------ 332ms Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 442 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 441 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: info: sendMpdCommand status took 441 milliseconds Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: 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} Oct 16 00:08:16 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: No code Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: 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} Oct 16 00:08:16 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio[1243]: info: No code Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:16 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:16 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.655Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.656Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.657Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.657Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.658Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.658Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.660Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.660Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.661Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.661Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.662Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:16 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:16.662Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:16 volumioboss volumio[1243]: info: ------------------------------ 514ms Oct 16 00:08:16 volumioboss volumio[1243]: info: ------------------------------ 513ms Oct 16 00:08:17 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:17 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:20 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:22 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:22 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:24 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:27 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:27 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:28 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:32 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:32 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:32 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:34 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 00:08:34 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 00:08:34 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:34 volumioboss volumio[1243]: info: Oct 16 00:08:34 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:34 volumioboss volumio[1243]: info: sendMpdCommand rescan took 2 milliseconds Oct 16 00:08:34 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:34 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:34 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:34 volumioboss volumio[1243]: info: Oct 16 00:08:34 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:34 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:34 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:34 volumioboss volumio[1243]: info: sendMpdCommand status took 4 milliseconds Oct 16 00:08:34 volumioboss volumio[1243]: info: sendMpdCommand status took 3 milliseconds Oct 16 00:08:34 volumioboss volumio[1243]: info: sendMpdCommand status took 2 milliseconds Oct 16 00:08:34 volumioboss volumio[1243]: info: sendMpdCommand status took 1 milliseconds Oct 16 00:08:34 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:35 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:35 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:35 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:35 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:35 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:35 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:08:35 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:35 volumioboss volumio[1243]: info: No code Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:35 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:08:35 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:35 volumioboss volumio[1243]: info: No code Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:35 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:35 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.090Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.091Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.093Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.093Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.094Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.094Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.097Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.097Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.098Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.098Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.099Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:35 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:35.100Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:35 volumioboss volumio[1243]: info: ------------------------------ 148ms Oct 16 00:08:35 volumioboss volumio[1243]: info: ------------------------------ 147ms Oct 16 00:08:36 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:37 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:37 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:39 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:08:39 volumioboss volumio[1243]: info: Oct 16 00:08:39 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:08:39 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:39 volumioboss volumio[1243]: info: Oct 16 00:08:39 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:39 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:39 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:39 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:39 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:39 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:08:39 volumioboss volumio[1243]: info: Oct 16 00:08:39 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:08:39 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:39 volumioboss volumio[1243]: info: Oct 16 00:08:39 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:39 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:39 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:39 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:39 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:40 volumioboss volumio[1243]: info: sendMpdCommand status took 248 milliseconds Oct 16 00:08:40 volumioboss volumio[1243]: info: sendMpdCommand status took 247 milliseconds Oct 16 00:08:40 volumioboss volumio[1243]: info: sendMpdCommand status took 248 milliseconds Oct 16 00:08:40 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:40 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:40 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:40 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:40 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: 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} Oct 16 00:08:40 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: No code Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: 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} Oct 16 00:08:40 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: No code Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.184Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.185Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.187Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.187Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.188Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.188Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.189Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.189Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.190Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.190Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.191Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.191Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio[1243]: info: ------------------------------ 329ms Oct 16 00:08:40 volumioboss volumio[1243]: info: ------------------------------ 330ms Oct 16 00:08:40 volumioboss volumio[1243]: info: sendMpdCommand status took 438 milliseconds Oct 16 00:08:40 volumioboss volumio[1243]: info: sendMpdCommand status took 432 milliseconds Oct 16 00:08:40 volumioboss volumio[1243]: info: sendMpdCommand status took 433 milliseconds Oct 16 00:08:40 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:40 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:40 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:40 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:40 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: 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} Oct 16 00:08:40 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: No code Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: 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} Oct 16 00:08:40 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio[1243]: info: No code Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:40 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.357Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.358Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.359Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.359Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.360Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.361Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.362Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.362Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.363Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.364Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.365Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:40 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:40.365Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:40 volumioboss volumio[1243]: info: ------------------------------ 507ms Oct 16 00:08:40 volumioboss volumio[1243]: info: ------------------------------ 507ms Oct 16 00:08:40 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:42 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:42 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand rescan Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:43 volumioboss volumio[1243]: info: Oct 16 00:08:43 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:43 volumioboss volumio[1243]: info: sendMpdCommand rescan took 2 milliseconds Oct 16 00:08:43 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:43 volumioboss volumio[1243]: info: Oct 16 00:08:43 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:43 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:43 volumioboss volumio[1243]: info: sendMpdCommand status took 10 milliseconds Oct 16 00:08:43 volumioboss volumio[1243]: info: sendMpdCommand status took 9 milliseconds Oct 16 00:08:43 volumioboss volumio[1243]: info: sendMpdCommand status took 8 milliseconds Oct 16 00:08:43 volumioboss volumio[1243]: info: sendMpdCommand status took 7 milliseconds Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:43 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:43 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatetrue Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:43 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:43 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:08:43 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:43 volumioboss volumio[1243]: info: No code Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:43 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 16 00:08:43 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:43 volumioboss volumio[1243]: info: No code Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:43 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:43 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.836Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.837Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.838Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.838Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.839Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.840Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.841Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.841Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.842Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:43 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:43.842Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:43 volumioboss volumio[1243]: info: ------------------------------ 121ms Oct 16 00:08:43 volumioboss volumio[1243]: info: ------------------------------ 120ms Oct 16 00:08:44 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:47 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:47 volumioboss volumio[1243]: info: Discovery: Restarting Browsing Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 00:08:48 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:08:48 volumioboss volumio[1243]: info: Oct 16 00:08:48 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:48 volumioboss volumio[1243]: info: Oct 16 00:08:48 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:48 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:48 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:48 volumioboss volumio[1243]: info: MPD Database updated - AlbumList cache refreshed Oct 16 00:08:48 volumioboss volumio[1243]: info: Oct 16 00:08:48 volumioboss volumio[1243]: ---------------------------- MPD announces state update: database Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:48 volumioboss volumio[1243]: info: Oct 16 00:08:48 volumioboss volumio[1243]: ---------------------------- MPD announces state update: update Oct 16 00:08:48 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:48 volumioboss volumio[1243]: info: ControllerMpd::getState Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::sendMpdCommand status Oct 16 00:08:48 volumioboss volumio[1243]: info: sendMpdCommand status took 207 milliseconds Oct 16 00:08:48 volumioboss volumio[1243]: info: sendMpdCommand status took 207 milliseconds Oct 16 00:08:48 volumioboss volumio[1243]: info: sendMpdCommand status took 207 milliseconds Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:48 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:48 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:48 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: 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} Oct 16 00:08:48 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:48 volumioboss volumio[1243]: info: No code Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:48 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: 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} Oct 16 00:08:48 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:48 volumioboss volumio[1243]: info: No code Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:48 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:48 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.975Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.975Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.976Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.976Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.976Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.976Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.977Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.978Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.978Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.978Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.980Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:48 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:48.980Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:48 volumioboss volumio[1243]: info: ------------------------------ 291ms Oct 16 00:08:48 volumioboss volumio[1243]: info: ------------------------------ 290ms Oct 16 00:08:49 volumioboss volumio[1243]: info: sendMpdCommand status took 470 milliseconds Oct 16 00:08:49 volumioboss volumio[1243]: info: sendMpdCommand status took 470 milliseconds Oct 16 00:08:49 volumioboss volumio[1243]: info: sendMpdCommand status took 470 milliseconds Oct 16 00:08:49 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:49 volumioboss volumio[1243]: info: Command Router : Notfying DB Updatefalse Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::Close All Modals sent Oct 16 00:08:49 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:49 volumioboss volumio[1243]: verbose: ControllerMpd::parseState Oct 16 00:08:49 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: 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} Oct 16 00:08:49 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:49 volumioboss volumio[1243]: info: No code Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:49 volumioboss volumio[1243]: info: ControllerMpd::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::servicePushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: 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} Oct 16 00:08:49 volumioboss volumio[1243]: verbose: CURRENT POSITION 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::syncState stateService stop Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::syncState currentStatus stop Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:49 volumioboss volumio[1243]: info: No code Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreStateMachine::pushState Oct 16 00:08:49 volumioboss volumio[1243]: info: CorePlayQueue::getTrack 0 Oct 16 00:08:49 volumioboss volumio[1243]: info: CoreCommandRouter::volumioPushState Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.228Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.228Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.229Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.230Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.231Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.231Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.236Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.236Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.237Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.237Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.238Z level=INFO msg="emitting player state changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" state=STATUS_STOPPED positionMs=0 volume=86 Oct 16 00:08:49 volumioboss volumio5-onboarding[1966]: time=2026-10-15T22:08:49.239Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.14:49470,00:00:00:00:00:00%02 @ 0x347c390" id= title= Oct 16 00:08:49 volumioboss volumio[1243]: info: ------------------------------ 551ms Oct 16 00:08:49 volumioboss volumio[1243]: info: ------------------------------ 552ms Oct 16 00:08:50 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 16 00:08:50 volumioboss volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 16 00:08:50 volumioboss dbus-daemon[772]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 16 00:08:50 volumioboss volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 00:08:50 volumioboss volumio[1243]: Error: dns service error: refused Oct 16 00:08:50 volumioboss volumio[1243]: at new Browser (/volumio/node_modules/mdns/lib/browser.js:88:10) Oct 16 00:08:50 volumioboss volumio[1243]: at Object.create [as createBrowser] (/volumio/node_modules/mdns/lib/browser.js:116:10) Oct 16 00:08:50 volumioboss volumio[1243]: at ControllerVolumioDiscovery.browseForService (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:718:32) Oct 16 00:08:50 volumioboss volumio[1243]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 16 00:08:50 volumioboss volumio[1243]: at ControllerNetworkfs.discoverShares (/volumio/app/plugins/system_controller/networkfs/index.js:826:36) Oct 16 00:08:50 volumioboss volumio[1243]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 16 00:08:50 volumioboss volumio[1243]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1614:45) Oct 16 00:08:50 volumioboss volumio[1243]: at Socket.emit (node:events:514:28) Oct 16 00:08:50 volumioboss volumio[1243]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 16 00:08:50 volumioboss volumio[1243]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 16 00:08:50 volumioboss volumio[1243]: errorCode: -65553 Oct 16 00:08:50 volumioboss volumio[1243]: } Oct 16 00:08:50 volumioboss volumio[1243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 00:08:51 volumioboss sudo[14466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 00:07' Oct 16 00:08:51 volumioboss sudo[14466]: 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"