Jun 01 16:40:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 16:40:00 volumio volumio[1250]: info: Received Get System Info Jun 01 16:40:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 16:40:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 16:40:00 volumio volumio[1250]: info: Discovery: Getting this device information Jun 01 16:40:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jun 01 16:40:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 16:40:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 16:40:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 16:40:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 16:40:01 volumio volumio-remote-updater[811]: Test mode disabled Jun 01 16:40:01 volumio volumio-remote-updater[811]: Alpha mode disabled Jun 01 16:40:01 volumio volumio-remote-updater[811]: Alpha legacy test mode disabled Jun 01 16:40:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 01 16:40:02 volumio volumio[1250]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 01 16:40:02 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 01 16:40:02 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:02 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:02 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:02 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:02 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:04 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 16:40:04 volumio volumio[1250]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 01 16:40:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jun 01 16:40:04 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue Jun 01 16:40:05 volumio volumio[1250]: info: CoreStateMachine::getQueue Jun 01 16:40:05 volumio volumio[1250]: info: CorePlayQueue::getQueue Jun 01 16:40:07 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:07 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:07 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:07 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:07 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 16:40:09 volumio volumio[1250]: info: Retrieving Cloud Streaming UI Jun 01 16:40:09 volumio volumio[1250]: info: Getting Tidal Cloud Configuration Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 16:40:09 volumio volumio[1250]: info: Getting Qobuz Cloud Configuration Jun 01 16:40:09 volumio volumio[1250]: info: Asking plugin for UI Config Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 16:40:09 volumio volumio[1250]: info: Getting Spotify Cloud Configuration Jun 01 16:40:09 volumio volumio[1250]: info: Asking plugin for UI Config Jun 01 16:40:09 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 16:40:09 volumio volumio[1250]: info: Saving Spotify Acccount Jun 01 16:40:09 volumio volumio[1250]: info: Got Tidal Cloud Configuration Jun 01 16:40:09 volumio volumio[1250]: info: Got it Jun 01 16:40:09 volumio volumio[1250]: info: Got it Jun 01 16:40:09 volumio volumio[1250]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 16:40:10 volumio volumio[1250]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 16:40:10 volumio volumio[1250]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 01 16:40:10 volumio volumio[1250]: info: Completed starting MyVolumio Plugin Jun 01 16:40:10 volumio volumio[1250]: [Metrics] CommandRouter: 49s 265.57ms Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::volumiosetStartupVolume Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 16:40:11 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 01 16:40:11 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 01 16:40:11 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 01 16:40:12 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:12 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:12 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:12 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:12 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 16:40:17 volumio volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 01 16:40:17 volumio volumio[1250]: info: Received Get System Version Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 16:40:17 volumio volumio[1250]: info: Received Get System Info Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 16:40:17 volumio volumio[1250]: info: Discovery: Getting this device information Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jun 01 16:40:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 16:40:17 volumio volumio[1250]: info: BOOT COMPLETED Jun 01 16:40:18 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:18 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:18 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:18 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:18 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:22 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:23 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:23 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:23 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:23 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:23 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:30 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:34 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:34 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:34 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:34 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:34 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:34 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:38 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand rescan Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: info: Jun 01 16:40:38 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand rescan took 13 milliseconds Jun 01 16:40:38 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: info: Jun 01 16:40:38 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:40:38 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: info: Jun 01 16:40:38 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:40:38 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: info: Jun 01 16:40:38 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand status took 53 milliseconds Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand status took 43 milliseconds Jun 01 16:40:38 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand status took 37 milliseconds Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand status took 64 milliseconds Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand status took 59 milliseconds Jun 01 16:40:38 volumio volumio[1250]: info: sendMpdCommand status took 33 milliseconds Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:38 volumio volumio[1250]: info: Command Router : Notfying DB Updatetrue Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:38 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:40:38 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:38 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:40:38 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:38 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.093+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.093+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.094+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.094+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.095+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.095+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:40:39 volumio volumio[1250]: info: ------------------------------ 462ms Jun 01 16:40:39 volumio volumio[1250]: info: ------------------------------ 444ms Jun 01 16:40:39 volumio volumio[1250]: info: ------------------------------ 436ms Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:39 volumio volumio[1250]: info: Jun 01 16:40:39 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:39 volumio volumio[1250]: info: Jun 01 16:40:39 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:40:39 volumio volumio[1250]: info: sendMpdCommand status took 456 milliseconds Jun 01 16:40:39 volumio volumio[1250]: info: sendMpdCommand status took 455 milliseconds Jun 01 16:40:39 volumio volumio[1250]: info: sendMpdCommand status took 47 milliseconds Jun 01 16:40:39 volumio volumio[1250]: info: sendMpdCommand status took 4 milliseconds Jun 01 16:40:39 volumio volumio[1250]: info: sendMpdCommand status took 3 milliseconds Jun 01 16:40:39 volumio volumio[1250]: info: sendMpdCommand status took 2 milliseconds Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:40:39 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:40:39 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:40:39 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:40:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:40:39 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.543+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.543+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.554+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.554+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.563+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:40:39 volumio volumio5-onboarding[1545]: time=2026-06-01T16:40:39.563+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:40:39 volumio volumio[1250]: info: ------------------------------ 896ms Jun 01 16:40:39 volumio volumio[1250]: info: ------------------------------ 486ms Jun 01 16:40:39 volumio volumio[1250]: info: ------------------------------ 442ms Jun 01 16:40:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:40 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:40 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:40 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:40 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:40 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:43 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:44 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 01 16:40:44 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 01 16:40:45 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:45 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:45 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:45 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:45 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:46 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:50 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Jun 01 16:40:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:50 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:50 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:50 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:50 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:50 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:54 volumio kernel: usb 1-1.3: USB disconnect, device number 3 Jun 01 16:40:54 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:56 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:56 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:56 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:40:56 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:40:56 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:40:58 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd Jun 01 16:40:58 volumio kernel: usb 2-1: New USB device found, idVendor=0bda, idProduct=9201, bcdDevice=f2.00 Jun 01 16:40:58 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 01 16:40:58 volumio kernel: usb 2-1: Product: RTL9201 Jun 01 16:40:58 volumio kernel: usb 2-1: Manufacturer: Realtek Jun 01 16:40:58 volumio kernel: usb 2-1: SerialNumber: 012345678999 Jun 01 16:40:58 volumio kernel: usb 2-1: Enable of device-initiated U1 failed. Jun 01 16:40:58 volumio kernel: usb 2-1: Enable of device-initiated U2 failed. Jun 01 16:40:58 volumio kernel: usb 2-1: Enable of device-initiated U1 failed. Jun 01 16:40:58 volumio kernel: usb 2-1: Enable of device-initiated U2 failed. Jun 01 16:40:58 volumio kernel: scsi host0: uas Jun 01 16:40:58 volumio kernel: scsi 0:0:0:0: Direct-Access MTFDDAV2 56TDL-1AW1ZABHA 1.02 PQ: 0 ANSI: 6 Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB) Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 37 00 00 08 Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jun 01 16:40:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes not a multiple of physical block size (4096 bytes) Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes) Jun 01 16:40:58 volumio kernel: sda: Jun 01 16:40:58 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 01 16:41:01 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:41:01 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:41:01 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:41:01 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:41:01 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:41:02 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand update Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: Jun 01 16:41:05 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand update took 27 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: Jun 01 16:41:05 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: Jun 01 16:41:05 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: Jun 01 16:41:05 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 46 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 20 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 19 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 19 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 12 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 10 milliseconds Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.406+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.406+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.407+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.408+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.410+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.410+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:41:05 volumio volumio[1250]: info: ------------------------------ 369ms Jun 01 16:41:05 volumio volumio[1250]: info: ------------------------------ 342ms Jun 01 16:41:05 volumio volumio[1250]: info: ------------------------------ 334ms Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: Jun 01 16:41:05 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: Jun 01 16:41:05 volumio volumio[1250]: ---------------------------- MPD announces state update: update Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::getState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 336 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 328 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 3 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 3 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 2 milliseconds Jun 01 16:41:05 volumio volumio[1250]: info: sendMpdCommand status took 1 milliseconds Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: Command Router : Notfying DB Updatefalse Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Jun 01 16:41:05 volumio volumio[1250]: verbose: ControllerMpd::parseState Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:41:05 volumio volumio[1250]: info: ControllerMpd::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jun 01 16:41:05 volumio volumio[1250]: info: CoreStateMachine::pushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jun 01 16:41:05 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 Jun 01 16:41:05 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.902+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.903+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.904+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.904+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.904+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" state=STATUS_STOPPED positionMs=0 volume=62 Jun 01 16:41:05 volumio volumio5-onboarding[1545]: time=2026-06-01T16:41:05.905+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.2:53822 @ 0x3000c00" id=https://ad1.xdevel.com/radiomarconi2 title="Radio Marconi 2" Jun 01 16:41:05 volumio volumio[1250]: info: ------------------------------ 856ms Jun 01 16:41:05 volumio volumio[1250]: info: ------------------------------ 523ms Jun 01 16:41:05 volumio volumio[1250]: info: ------------------------------ 522ms Jun 01 16:41:06 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:41:07 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:41:07 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:41:07 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:41:07 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:41:07 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:41:10 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:41:12 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:41:12 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:41:12 volumio volumio[1250]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 01 16:41:12 volumio volumio[1250]: info: Discovery: Restarting Browsing Jun 01 16:41:12 volumio volumio[1250]: info: Discovery: Restart already pending, ignoring duplicate call Jun 01 16:41:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 16:41:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 01 16:41:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 01 16:41:17 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 16:41:17 volumio volumio[1250]: Error: getaddrinfo -3007 Jun 01 16:41:17 volumio volumio[1250]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jun 01 16:41:17 volumio volumio[1250]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jun 01 16:41:17 volumio volumio[1250]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jun 01 16:41:17 volumio volumio[1250]: code: -3007, Jun 01 16:41:17 volumio volumio[1250]: errno: -3007, Jun 01 16:41:17 volumio volumio[1250]: syscall: 'getaddrinfo' Jun 01 16:41:17 volumio volumio[1250]: } Jun 01 16:41:17 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 16:41:19 volumio sudo[2080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 16:40' Jun 01 16:41:19 volumio sudo[2080]: 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"