Aug 29 16:28:02 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:06 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:09 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:09 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:10 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:14 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:18 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:19 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:19 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:19 kitchen-speaker volumio[1574]: info: Listing playlists
Aug 29 16:28:19 kitchen-speaker volumio[1574]: info: Listing playlists
Aug 29 16:28:22 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand rescan
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:23 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand rescan took 10 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:23 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:23 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:23 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 10 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 8 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 7 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 7 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 6 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 5 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:23 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:23 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 127 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 125 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 77 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 61 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 44 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 7 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 7 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 6 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 5 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.467-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.467-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.470-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.471-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.473-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.473-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ------------------------------ 288ms
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ------------------------------ 285ms
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ------------------------------ 284ms
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 155 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 135 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 117 milliseconds
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.560-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.561-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.564-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.565-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.568-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:23 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:23.568-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ------------------------------ 363ms
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ------------------------------ 242ms
Aug 29 16:28:23 kitchen-speaker volumio[1574]: info: ------------------------------ 241ms
Aug 29 16:28:26 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:29 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:29 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:30 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand update took 7 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:30 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:30 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:30 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 9 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 9 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 8 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 7 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 7 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 6 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:30 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info:
Aug 29 16:28:30 kitchen-speaker volumio[1574]: ---------------------------- MPD announces state update: update
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::getState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand status
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 91 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 90 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 63 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 46 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 31 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 6 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 5 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 6 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand status took 5 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Command Router : Notfying DB Updatefalse
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::Close All Modals sent
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.884-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.884-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.887-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.887-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.890-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.891-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ------------------------------ 229ms
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ------------------------------ 228ms
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ------------------------------ 227ms
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 137 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 116 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: sendMpdCommand playlistinfo took 101 milliseconds
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: verbose: ControllerMpd::parseTrackInfo
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ControllerMpd::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::servicePushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreStateMachine::pushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioPushState
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.969-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.970-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.972-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.972-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.974-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" state=STATUS_STOPPED positionMs=500 volume=86
Aug 29 16:28:30 kitchen-speaker volumio5-onboarding[1480]: time=2026-08-29T16:28:30.975-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.31:54778 @ 0x2bfe420" id="https://playerservices.streamtheworld.com/api/livestream-redirect/WNNXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImxhdCI6IjMzLjk0NjEiLCJsb25nIjoiLTgzLjQxOCIsImlhdCI6MTc4Nzg0OTQ4NSwiaXNzIjoidGlzcnYifQ.Ms66258B1OBVxV3bV8nCGRv6Vdwky0SX64qxcgp1ioA" title=99x
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ------------------------------ 301ms
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ------------------------------ 215ms
Aug 29 16:28:30 kitchen-speaker volumio[1574]: info: ------------------------------ 214ms
Aug 29 16:28:34 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:38 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:39 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:39 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:39 kitchen-speaker volumio[1574]: info: Listing playlists
Aug 29 16:28:39 kitchen-speaker volumio[1574]: info: Listing playlists
Aug 29 16:28:42 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:42 kitchen-speaker volumio[1574]: info: Enabling MyMusic plugin bluetooth
Aug 29 16:28:42 kitchen-speaker volumio[1574]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth
Aug 29 16:28:42 kitchen-speaker volumio[1574]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling
Aug 29 16:28:42 kitchen-speaker volumio[1574]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling
Aug 29 16:28:42 kitchen-speaker volumio[1574]: error: Could not Enable MyMusic Plugin: Error
Aug 29 16:28:46 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:49 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:49 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:50 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:54 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:58 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:28:59 kitchen-speaker volumio[1574]: info: CoreCommandRouter::volumioGetState
Aug 29 16:28:59 kitchen-speaker volumio[1574]: info: CorePlayQueue::getTrack 0
Aug 29 16:28:59 kitchen-speaker volumio[1574]: info: Listing playlists
Aug 29 16:28:59 kitchen-speaker volumio[1574]: info: Listing playlists
Aug 29 16:29:02 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:29:06 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 29 16:29:06 kitchen-speaker volumio[1574]: info: Disabling MyMusic plugin upnp
Aug 29 16:29:06 kitchen-speaker sudo[2731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Aug 29 16:29:06 kitchen-speaker sudo[2731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 29 16:29:06 kitchen-speaker systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD...
Aug 29 16:29:08 kitchen-speaker volumio[1574]: info: Enabling MyMusic plugin upnp
Aug 29 16:29:08 kitchen-speaker volumio[1574]: info: Enabling plugin upnp
Aug 29 16:29:08 kitchen-speaker volumio[1574]: info: Loading plugin "upnp"...
Aug 29 16:29:08 kitchen-speaker volumio[1574]: info: [1788035348410] Starting Upmpd Daemon
Aug 29 16:29:08 kitchen-speaker volumio[1574]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 29 16:29:08 kitchen-speaker volumio[1574]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 29 16:29:08 kitchen-speaker volumio[1574]: Error: listen EADDRINUSE: address already in use :::6599
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at Server.setupListenHandle [as _listen2] (node:net:1872:16)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at listenInCluster (node:net:1920:12)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at Server.listen (node:net:2008:7)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Aug 29 16:29:08 kitchen-speaker volumio[1574]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) {
Aug 29 16:29:08 kitchen-speaker volumio[1574]: code: 'EADDRINUSE',
Aug 29 16:29:08 kitchen-speaker volumio[1574]: errno: -98,
Aug 29 16:29:08 kitchen-speaker volumio[1574]: syscall: 'listen',
Aug 29 16:29:08 kitchen-speaker volumio[1574]: address: '::',
Aug 29 16:29:08 kitchen-speaker volumio[1574]: port: 6599
Aug 29 16:29:08 kitchen-speaker volumio[1574]: }
Aug 29 16:29:08 kitchen-speaker volumio[1574]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 29 16:29:09 kitchen-speaker sudo[2747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 16:28'
Aug 29 16:29:09 kitchen-speaker sudo[2747]: 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"