Feb 26 22:02:03 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:03 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:03 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:03 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:03 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:03 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:04 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand rescan Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand rescan took 1 milliseconds Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: Feb 26 22:02:07 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: Feb 26 22:02:07 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: Feb 26 22:02:07 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: Feb 26 22:02:07 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 4 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: Command Router : Notfying DB Updatetrue Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.409+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:07 volumio volumio[1848]: info: ------------------------------ 31ms Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.409+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: Feb 26 22:02:07 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: Feb 26 22:02:07 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 30 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 30 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 30 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 30 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 30 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:07 volumio volumio[1848]: info: sendMpdCommand status took 1 milliseconds Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:07 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.451+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.452+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:07 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:07 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:07 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:07 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:07 volumio volumio[1848]: info: ------------------------------ 76ms Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.463+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.463+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.463+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.463+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.463+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.463+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.464+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:07 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:07.464+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:07 volumio volumio[1848]: info: ------------------------------ 85ms Feb 26 22:02:07 volumio volumio[1848]: info: ------------------------------ 84ms Feb 26 22:02:07 volumio volumio[1848]: info: ------------------------------ 56ms Feb 26 22:02:07 volumio volumio[1848]: info: ------------------------------ 56ms Feb 26 22:02:09 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:09 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:09 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:09 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:09 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand update Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: Feb 26 22:02:09 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand update took 1 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: Feb 26 22:02:09 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: Feb 26 22:02:09 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: Feb 26 22:02:09 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 3 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 3 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 1 milliseconds Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.271+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.271+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.272+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.272+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.272+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.272+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:09 volumio volumio[1848]: info: ------------------------------ 40ms Feb 26 22:02:09 volumio volumio[1848]: info: ------------------------------ 40ms Feb 26 22:02:09 volumio volumio[1848]: info: ------------------------------ 40ms Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: Feb 26 22:02:09 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: Feb 26 22:02:09 volumio volumio[1848]: ---------------------------- MPD announces state update: update Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::getState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::sendMpdCommand status Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 41 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 40 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 2 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 1 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 1 milliseconds Feb 26 22:02:09 volumio volumio[1848]: info: sendMpdCommand status took 1 milliseconds Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: Command Router : Notfying DB Updatefalse Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::Close All Modals sent Feb 26 22:02:09 volumio volumio[1848]: verbose: ControllerMpd::parseState Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:09 volumio volumio[1848]: info: ControllerMpd::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::servicePushState Feb 26 22:02:09 volumio volumio[1848]: info: CoreStateMachine::pushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: CoreCommandRouter::volumioPushState Feb 26 22:02:09 volumio volumio[1848]: info: CorePlayQueue::getTrack 0 Feb 26 22:02:09 volumio volumio[1848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.318+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.318+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.318+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.318+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.319+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 22:02:09 volumio volumio5-onboarding[2214]: time=2026-02-26T22:02:09.319+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.145:51741 @ 0x245acc0" id=http://icecast.unitedradio.it/Virgin.mp3 title="Virgin Radio Italy" Feb 26 22:02:09 volumio volumio[1848]: info: ------------------------------ 85ms Feb 26 22:02:09 volumio volumio[1848]: info: ------------------------------ 47ms Feb 26 22:02:09 volumio volumio[1848]: info: ------------------------------ 46ms Feb 26 22:02:11 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:14 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:14 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:14 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:14 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:14 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:15 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:19 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:19 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:19 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:19 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:19 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:19 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:23 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:24 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:24 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:24 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:24 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:24 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:27 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:29 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:29 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:29 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:29 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:29 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:31 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:34 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:34 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:34 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:34 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:34 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:35 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:39 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:39 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:39 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:39 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:39 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:39 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:43 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:44 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:44 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:44 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:44 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:44 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:47 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:49 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:49 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:49 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:49 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:49 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:51 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:54 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:54 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:54 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:54 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:54 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:02:55 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:59 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 22:02:59 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:59 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:59 volumio volumio[1848]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 26 22:02:59 volumio volumio[1848]: info: Discovery: Restarting Browsing Feb 26 22:02:59 volumio volumio[1848]: info: Discovery: Restart already pending, ignoring duplicate call Feb 26 22:03:01 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 26 22:03:01 volumio volumio[1848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 26 22:03:01 volumio volumio[1848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 22:03:01 volumio volumio[1848]: Error: getaddrinfo -3007 Feb 26 22:03:01 volumio volumio[1848]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 26 22:03:01 volumio volumio[1848]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 26 22:03:01 volumio volumio[1848]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 26 22:03:01 volumio volumio[1848]: code: -3007, Feb 26 22:03:01 volumio volumio[1848]: errno: -3007, Feb 26 22:03:01 volumio volumio[1848]: syscall: 'getaddrinfo' Feb 26 22:03:01 volumio volumio[1848]: } Feb 26 22:03:01 volumio volumio[1848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 22:03:01 volumio sudo[3032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 22:02' Feb 26 22:03:01 volumio sudo[3032]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"