Oct 11 18:54:04 living-room ntpd[1001]: PROTO: 178.62.118.211 unlink local addr 192.168.1.103 -> Oct 11 18:54:04 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::ClearQueue Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::clearPlayQueue Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::addQueueItems Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::addQueueItems Oct 11 18:54:04 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:54:04 living-room volumio[1771]: info: Adding Item to queue: music-library/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On Oct 11 18:54:04 living-room volumio[1771]: info: Using cached record of: music-library/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::updateTrackBlock Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrackBlock Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPlay Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::play index 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::play index undefined Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::startPlaybackTimer Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::clearAddPlayTracks USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand stop took 4 milliseconds Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:04 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand clear took 1 milliseconds Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand add "USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:04 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:04 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:04 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:04 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 4ms Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand add "USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" took 4 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 3ms Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 3ms Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand play Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:04 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:04 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 31ms Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand play took 29 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 30ms Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 5ms Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand status took 5 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand status took 4 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand status took 4 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand status took 3 milliseconds Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:04 living-room volumio[1771]: info: Oct 11 18:54:04 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand status took 9 milliseconds Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:54:04 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:54:04 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:04 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:54:04 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:04 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:54:04 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:04 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 41ms Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.694+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.694+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.696+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.697+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.699+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.699+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.701+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.701+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.703+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.704+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.705+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.705+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 90ms Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 87ms Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 87ms Oct 11 18:54:04 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 73 milliseconds Oct 11 18:54:04 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:04 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:54:04 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:04 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.756+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.756+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.757+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:04 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:04.758+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:04 living-room volumio[1771]: info: ------------------------------ 108ms Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:04 living-room volumio[1771]: info: CorePlayQueue::getTrack 1 Oct 11 18:54:08 living-room ntpd[1001]: PROTO: 185.137.221.158 unlink local addr 192.168.1.103 -> Oct 11 18:54:08 living-room volumio[1771]: info: Oct 11 18:54:08 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:08 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:08 living-room volumio[1771]: info: Oct 11 18:54:08 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:08 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:08 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:08 living-room volumio[1771]: info: Oct 11 18:54:08 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:08 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:08 living-room volumio[1771]: info: Oct 11 18:54:08 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:08 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:08 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:08 living-room volumio[1771]: info: Oct 11 18:54:08 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:08 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:08 living-room volumio[1771]: info: Oct 11 18:54:08 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:08 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:54:08 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:54:08 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:08 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:08 living-room volumio[1771]: info: ------------------------------ 6ms Oct 11 18:54:08 living-room volumio[1771]: info: sendMpdCommand status took 5 milliseconds Oct 11 18:54:08 living-room volumio[1771]: info: ------------------------------ 4ms Oct 11 18:54:08 living-room volumio[1771]: info: sendMpdCommand status took 3 milliseconds Oct 11 18:54:08 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:08 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:08 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:54:08 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::stPlaybackTimer Oct 11 18:54:08 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:54:08 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio[1771]: info: No code Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.680+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=4878 volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.681+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.682+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.683+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id= title= Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.685+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.686+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.689+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.690+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.692+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.692+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio[1771]: info: ------------------------------ 79ms Oct 11 18:54:08 living-room volumio[1771]: info: ------------------------------ 78ms Oct 11 18:54:08 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:54:08 living-room volumio[1771]: info: ------------------------------ 78ms Oct 11 18:54:08 living-room volumio[1771]: info: sendMpdCommand status took 77 milliseconds Oct 11 18:54:08 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:08 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:54:08 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio[1771]: info: No code Oct 11 18:54:08 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:08 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:08 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.749+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.749+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.751+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.752+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.754+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:54:08 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:08.755+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:54:08 living-room volumio[1771]: info: ------------------------------ 116ms Oct 11 18:54:12 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 11 18:54:12 living-room volumio[1771]: info: CURURI: music-library/USB/BFEC-3C19/[0 new/0 new new Oct 11 18:54:12 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:54:14 living-room kernel: hwmon hwmon1: Undervoltage detected! Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.138+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.135890464s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.186+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.235+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=48.389121ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.244+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=53.524084ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.262+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=72.466964ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.324+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://www.googleapis.com duration=136.369512ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.334+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://google.com duration=146.667562ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.345+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://securetoken.googleapis.com duration=157.265243ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.350+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=162.169894ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.353+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=http://pushupdates.volumio.org duration=164.747115ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.358+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://database.volumio.cloud duration=168.188496ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.392+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://functions.volumio.cloud duration=202.292359ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.402+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=https://functions.volumio.cloud duration=212.753584ms Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.698+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=http://cddb.volumio.org duration=509.877792ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 11 18:54:16 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:16.834+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.13678064s timeout=10s endpoint=http://plugins.volumio.org duration=644.075706ms Oct 11 18:54:16 living-room sudo[6690]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 18:54:16 living-room sudo[6692]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 18:54:16 living-room sudo[6690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:54:16 living-room sudo[6692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:54:16 living-room sudo[6690]: pam_unix(sudo:session): session closed for user root Oct 11 18:54:16 living-room sudo[6692]: pam_unix(sudo:session): session closed for user root Oct 11 18:54:17 living-room volumio[1771]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7a Build/CP3A.260905.009; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 11 18:54:17 living-room sudo[6696]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 18:54:17 living-room sudo[6696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:54:17 living-room sudo[6696]: pam_unix(sudo:session): session closed for user root Oct 11 18:54:17 living-room sudo[6698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 18:54:17 living-room sudo[6698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:54:17 living-room sudo[6698]: pam_unix(sudo:session): session closed for user root Oct 11 18:54:17 living-room volumio[1771]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7a Build/CP3A.260905.009; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 18:54:17 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 18:54:17 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:18 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::volumioGetQueue Oct 11 18:54:18 living-room volumio[1771]: info: CoreStateMachine::getQueue Oct 11 18:54:18 living-room volumio[1771]: info: CorePlayQueue::getQueue Oct 11 18:54:18 living-room volumio[1771]: info: Listing playlists Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 18:54:18 living-room volumio[1771]: info: Received Get System Info Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 18:54:18 living-room volumio[1771]: info: Discovery: Getting this device information Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:18 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:18 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 11 18:54:18 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 18:54:19 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 18:54:19 living-room volumio[1771]: info: Received Get System Info Oct 11 18:54:19 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 18:54:19 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 18:54:19 living-room volumio[1771]: info: Discovery: Getting this device information Oct 11 18:54:19 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:19 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:19 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 18:54:19 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 11 18:54:20 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 18:54:20 living-room volumio[1771]: info: Received Get System Info Oct 11 18:54:20 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 18:54:20 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 18:54:20 living-room volumio[1771]: info: Discovery: Getting this device information Oct 11 18:54:20 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:20 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:20 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 18:54:20 living-room kernel: hwmon hwmon1: Voltage normalised Oct 11 18:54:27 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 11 18:54:31 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:31.865+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s Oct 11 18:54:31 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:31.932+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=66.871693ms Oct 11 18:54:31 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:31.935+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=70.005837ms Oct 11 18:54:31 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:31.948+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=81.531168ms Oct 11 18:54:31 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:31.951+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=http://pushupdates.volumio.org duration=84.513541ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.020+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://www.googleapis.com duration=153.546417ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.035+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://securetoken.googleapis.com duration=168.861356ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.038+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://database.volumio.cloud duration=172.498881ms Oct 11 18:54:32 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 18:54:32 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 18:54:32 living-room volumio[1771]: info: Discovery: Getting this device information Oct 11 18:54:32 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:32 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:32 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.050+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=183.905463ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.053+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://functions.volumio.cloud duration=186.855181ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.057+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://google.com duration=190.907963ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.072+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=https://functions.volumio.cloud duration=206.897637ms Oct 11 18:54:32 living-room volumio[1771]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.102 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 11 18:54:32 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 18:54:32 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.431+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=http://plugins.volumio.org duration=565.769983ms Oct 11 18:54:32 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:32.460+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.102:36388 @ 0x278a1b0" latency=-2.120507774s timeout=10s endpoint=http://cddb.volumio.org duration=593.293217ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 18:54:36 living-room volumio[1771]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 11 18:54:36 living-room volumio[1771]: info: Received Get System Version Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 18:54:36 living-room volumio[1771]: info: Received Get System Info Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 18:54:36 living-room volumio[1771]: info: Discovery: Getting this device information Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::volumioGetState Oct 11 18:54:36 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:36 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 18:54:48 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::ClearQueue Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::clearPlayQueue Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::addQueueItems Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::addQueueItems Oct 11 18:54:48 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:54:48 living-room volumio[1771]: info: Adding Item to queue: music-library/USB/BFEC-3C19/[0 new/Frank Deluxe Oct 11 18:54:48 living-room volumio[1771]: info: Exploding uri music-library/USB/BFEC-3C19/[0 new/Frank Deluxe in service mpd Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F01%20-%20Intro%20%20Stronger%20Than%20Me.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F02%20-%20You%20Sent%20Me%20Flying.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/02 - You Sent Me Flying.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F03%20-%20Know%20You%20Now.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/03 - Know You Now.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F04%20-%20Fuck%20Me%20Pumps.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/04 - Fuck Me Pumps.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F05%20-%20I%20Heard%20Love%20Is%20Blind.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/05 - I Heard Love Is Blind.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F06%20-%20Moody's%20Mood%20For%20Love.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/06 - Moody's Mood For Love.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F07%20-%20(There%20Is)%20No%20Greater%20Love.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/07 - (There Is) No Greater Love.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F08%20-%20In%20My%20Bed.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/08 - In My Bed.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F09%20-%20Take%20The%20Box.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/09 - Take The Box.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F10%20-%20October%20Song.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/10 - October Song.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F11%20-%20What%20It%20Is%20About%20Men.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/11 - What It Is About Men.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F12%20-%20Help%20Yourself.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/12 - Help Yourself.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F13%20-%20Amy%20Amy%20Amy%20%20Outro.flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/13 - Amy Amy Amy Outro.flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank%20(Bonus%20CD)/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F14%20-%20Take%20The%20Box%20(Original%20Demo).flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/14 - Take The Box (Original Demo).flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank%20(Bonus%20CD)/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F15%20-%20I%20Heard%20Love%20Is%20Blind%20(Original%20Demo).flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/15 - I Heard Love Is Blind (Original Demo).flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank%20(Bonus%20CD)/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F16%20-%20Someone%20To%20Watch%20Over%20Me%20(Original%20Demo).flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/16 - Someone To Watch Over Me (Original Demo).flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank%20(Bonus%20CD)/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F17%20-%20What%20It%20Is%20(Original%20Demo).flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/17 - What It Is (Original Demo).flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank%20(Bonus%20CD)/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F18%20-%20Teach%20Me%20Tonight%20(Hootenanny).flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/18 - Teach Me Tonight (Hootenanny).flac Oct 11 18:54:48 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Frank%20(Bonus%20CD)/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FFrank%20Deluxe%2F19%20-%20'round%20Midnight%20(B-Side).flac&metadata=false Oct 11 18:54:48 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/19 - 'round Midnight (B-Side).flac Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::updateTrackBlock Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrackBlock Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPlay Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::play index 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::play index undefined Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::startPlaybackTimer Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::clearAddPlayTracks USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand stop took 4 milliseconds Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:48 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand clear took 3 milliseconds Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand add "USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:48 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:48 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:48 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:48 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 12ms Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand add "USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" took 11 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 10ms Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 5ms Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand play Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:48 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:54:48 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 8ms Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand play took 5 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 4ms Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 3ms Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand status took 5 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:48 living-room volumio[1771]: info: Oct 11 18:54:48 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand status took 10 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand status took 8 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand status took 7 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand status took 5 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand status took 3 milliseconds Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":664,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"372 Kbps","isStreaming":false,"title":"Intro / Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac","trackType":"flac"} Oct 11 18:54:48 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 21ms Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 18:54:48 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:48 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"398 Kbps","isStreaming":false,"title":"Intro / Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac","trackType":"flac"} Oct 11 18:54:48 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:48 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"398 Kbps","isStreaming":false,"title":"Intro / Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac","trackType":"flac"} Oct 11 18:54:48 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:48 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"398 Kbps","isStreaming":false,"title":"Intro / Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac","trackType":"flac"} Oct 11 18:54:48 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:48 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"398 Kbps","isStreaming":false,"title":"Intro / Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac","trackType":"flac"} Oct 11 18:54:48 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:48 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"398 Kbps","isStreaming":false,"title":"Intro / Stronger Than Me","artist":"Amy Winehouse","album":"Frank","uri":"USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac","trackType":"flac"} Oct 11 18:54:48 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:54:48 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:54:48 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:54:48 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.632+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.633+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.634+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.634+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.636+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.637+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.638+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.638+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.640+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.641+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.642+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.643+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.644+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.645+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.646+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.647+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.648+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.649+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.650+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:54:48 living-room volumio5-onboarding[1473]: time=2026-10-11T18:54:48.651+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 152ms Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 151ms Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 150ms Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 146ms Oct 11 18:54:48 living-room volumio[1771]: info: ------------------------------ 146ms Oct 11 18:55:01 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::ClearQueue Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::stPlaybackTimer Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::updateTrackBlock Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrackBlock Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::serviceStop Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::serviceStop Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::stop Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::clearPlayQueue Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::addQueueItems Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::addQueueItems Oct 11 18:55:01 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:55:01 living-room volumio[1771]: info: Adding Item to queue: music-library/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On Oct 11 18:55:01 living-room volumio[1771]: info: Using cached record of: music-library/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.613+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.614+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/Frank Deluxe/01 - Intro Stronger Than Me.flac" title="Intro / Stronger Than Me" Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::updateTrackBlock Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrackBlock Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPlay Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::play index 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::play index undefined Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::startPlaybackTimer Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::clearAddPlayTracks USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand stop took 35 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand stop took 8 milliseconds Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:01 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 6 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand clear took 6 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 4 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 3 milliseconds Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand add "USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: No code Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: No code Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.683+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.684+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.687+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.689+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.691+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.691+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.693+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.694+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.696+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.698+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.698+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.698+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 94ms Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 94ms Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:01 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:01 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:01 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:01 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 97ms Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 96 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand add "USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" took 96 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 6ms Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 5ms Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand play Oct 11 18:55:01 living-room volumio[1771]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 106ms Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:01 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:01 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 29ms Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand play took 27 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 26ms Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 25ms Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 14 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 6 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 4 milliseconds Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: Oct 11 18:55:01 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 1 Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 10 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 8 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand status took 8 milliseconds Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:01 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:01 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 47ms Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.824+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.825+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.825+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.826+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.828+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.829+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.830+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.831+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 78ms Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 71ms Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 57 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 56 milliseconds Oct 11 18:55:01 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 57 milliseconds Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:01 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:01 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:01 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:01 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:01 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:01 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.887+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.887+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.889+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.891+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.892+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.894+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.894+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.895+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.897+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.897+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.896+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=0 volume=49 Oct 11 18:55:01 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:01.898+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 147ms Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 144ms Oct 11 18:55:01 living-room volumio[1771]: info: ------------------------------ 144ms Oct 11 18:55:05 living-room volumio[1771]: info: Oct 11 18:55:05 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:05 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:05 living-room volumio[1771]: info: Oct 11 18:55:05 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:05 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:05 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:05 living-room volumio[1771]: info: Oct 11 18:55:05 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:05 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:05 living-room volumio[1771]: info: Oct 11 18:55:05 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:05 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:05 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:05 living-room volumio[1771]: info: Oct 11 18:55:05 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:05 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:05 living-room volumio[1771]: info: Oct 11 18:55:05 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:05 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:05 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:05 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:05 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:05 living-room volumio[1771]: info: ------------------------------ 6ms Oct 11 18:55:05 living-room volumio[1771]: info: sendMpdCommand status took 5 milliseconds Oct 11 18:55:05 living-room volumio[1771]: info: ------------------------------ 4ms Oct 11 18:55:05 living-room volumio[1771]: info: sendMpdCommand status took 3 milliseconds Oct 11 18:55:05 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:05 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:05 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:05 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::stPlaybackTimer Oct 11 18:55:05 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:05 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio[1771]: info: No code Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.814+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=3936 volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.815+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.816+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id= title= Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.815+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.818+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.820+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.821+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.822+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.822+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.824+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio[1771]: info: ------------------------------ 71ms Oct 11 18:55:05 living-room volumio[1771]: info: ------------------------------ 70ms Oct 11 18:55:05 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:05 living-room volumio[1771]: info: ------------------------------ 69ms Oct 11 18:55:05 living-room volumio[1771]: info: sendMpdCommand status took 68 milliseconds Oct 11 18:55:05 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:05 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:05 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio[1771]: info: No code Oct 11 18:55:05 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:05 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:05 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.878+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=121 volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.879+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.881+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=121 volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.883+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.883+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=121 volume=49 Oct 11 18:55:05 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:05.884+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:05 living-room volumio[1771]: info: ------------------------------ 123ms Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPlay Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::play index 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::play index undefined Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::startPlaybackTimer Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::clearAddPlayTracks USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand stop took 0 milliseconds Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand clear took 1 milliseconds Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand add "USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:17 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:17 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:17 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:17 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand add "USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" took 3 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 3ms Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 3ms Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand play Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:17 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:17 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 7ms Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 6ms Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand play took 5 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 4ms Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 3ms Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand status took 28 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand status took 27 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand status took 26 milliseconds Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:17 living-room volumio[1771]: info: Oct 11 18:55:17 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand status took 7 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand status took 4 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand status took 3 milliseconds Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:17 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:17 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:17 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:17 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:17 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 62ms Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.366+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=458 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.368+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.369+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=458 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.369+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.370+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=458 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.371+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.373+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=458 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.373+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 94ms Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 94ms Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 59 milliseconds Oct 11 18:55:17 living-room volumio[1771]: info: sendMpdCommand playlistinfo took 59 milliseconds Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:17 living-room volumio[1771]: verbose: ControllerMpd::parseTrackInfo Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:17 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:17 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:17 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:17 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":4,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"There's a Riot Goin' On","artist":"Sly and the Family Stone","album":"There's a Riot Goin' On","uri":"USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac","trackType":"flac"} Oct 11 18:55:17 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState stateService play Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:17 living-room volumio[1771]: info: Received an update from plugin. extracting info from payload Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.422+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.423+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.424+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.425+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.427+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.428+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.431+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.432+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.436+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.437+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.437+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=871 volume=49 Oct 11 18:55:17 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:17.437+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 145ms Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 143ms Oct 11 18:55:17 living-room volumio[1771]: info: ------------------------------ 143ms Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:17 living-room volumio[1771]: info: CorePlayQueue::getTrack 1 Oct 11 18:55:21 living-room volumio[1771]: info: Oct 11 18:55:21 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:21 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:21 living-room volumio[1771]: info: Oct 11 18:55:21 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:21 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:21 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:21 living-room volumio[1771]: info: Oct 11 18:55:21 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:21 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:21 living-room volumio[1771]: info: Oct 11 18:55:21 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:21 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:21 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:21 living-room volumio[1771]: info: Oct 11 18:55:21 living-room volumio[1771]: ---------------------------- MPD announces system playlist update Oct 11 18:55:21 living-room volumio[1771]: info: Ignoring MPD Status Update Oct 11 18:55:21 living-room volumio[1771]: info: Oct 11 18:55:21 living-room volumio[1771]: ---------------------------- MPD announces state update: player Oct 11 18:55:21 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:21 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:21 living-room volumio[1771]: info: ControllerMpd::getState Oct 11 18:55:21 living-room volumio[1771]: verbose: ControllerMpd::sendMpdCommand status Oct 11 18:55:21 living-room volumio[1771]: info: ------------------------------ 7ms Oct 11 18:55:21 living-room volumio[1771]: info: sendMpdCommand status took 6 milliseconds Oct 11 18:55:21 living-room volumio[1771]: info: ------------------------------ 4ms Oct 11 18:55:21 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:21 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:21 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus play Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::stPlaybackTimer Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.354+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_PLAYING positionMs=4877 volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.355+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.357+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs=0 volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.357+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id= title= Oct 11 18:55:21 living-room volumio[1771]: info: ------------------------------ 42ms Oct 11 18:55:21 living-room volumio[1771]: error: updateQueue error: null Oct 11 18:55:21 living-room volumio[1771]: info: sendMpdCommand status took 40 milliseconds Oct 11 18:55:21 living-room volumio[1771]: info: ------------------------------ 39ms Oct 11 18:55:21 living-room volumio[1771]: info: sendMpdCommand status took 37 milliseconds Oct 11 18:55:21 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:21 living-room volumio[1771]: verbose: ControllerMpd::parseState Oct 11 18:55:21 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:21 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: No code Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: ControllerMpd::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::servicePushState Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 11 18:55:21 living-room volumio[1771]: verbose: CURRENT POSITION 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::syncState stateService stop Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::syncState currentStatus stop Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio[1771]: info: No code Oct 11 18:55:21 living-room volumio[1771]: info: CoreStateMachine::pushState Oct 11 18:55:21 living-room volumio[1771]: info: CorePlayQueue::getTrack 0 Oct 11 18:55:21 living-room volumio[1771]: info: CoreCommandRouter::volumioPushState Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.397+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.397+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.400+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.400+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.402+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.403+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.405+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.406+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.408+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.409+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.410+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" state=STATUS_STOPPED positionMs= volume=49 Oct 11 18:55:21 living-room volumio5-onboarding[1473]: time=2026-10-11T18:55:21.411+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:36388 @ 0x278a1b0" id="mnt/USB/BFEC-3C19/[0 new/1971 - There's a Riot Goin' On/06. There's a Riot Goin' On.flac" title="There's a Riot Goin' On" Oct 11 18:55:21 living-room volumio[1771]: info: ------------------------------ 120ms Oct 11 18:55:21 living-room volumio[1771]: info: ------------------------------ 119ms Oct 11 18:55:23 living-room systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Oct 11 18:55:25 living-room systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Oct 11 18:55:25 living-room systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Oct 11 18:55:30 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:55:30 living-room volumio[1771]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 18:55:30 living-room volumio[1771]: info: CoreStateMachine::ClearQueue Oct 11 18:55:30 living-room volumio[1771]: info: CoreStateMachine::stop Oct 11 18:55:30 living-room volumio[1771]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 18:55:30 living-room volumio[1771]: info: CorePlayQueue::clearPlayQueue Oct 11 18:55:30 living-room volumio[1771]: info: CorePlayQueue::saveQueue Oct 11 18:55:30 living-room volumio[1771]: info: CoreCommandRouter::volumioPushQueue Oct 11 18:55:31 living-room volumio[1771]: info: CoreStateMachine::addQueueItems Oct 11 18:55:31 living-room volumio[1771]: info: CorePlayQueue::addQueueItems Oct 11 18:55:31 living-room volumio[1771]: info: Preload queue cleared Oct 11 18:55:31 living-room volumio[1771]: info: Adding Item to queue: music-library/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3] Oct 11 18:55:31 living-room volumio[1771]: info: Exploding uri music-library/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3] in service mpd Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F01%20-%20Rehab.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/01 - Rehab.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F02%20-%20You%20Know%20I'm%20No%20Good.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/02 - You Know I'm No Good.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F03%20-%20Me%20%26%20Mr%20Jones.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/03 - Me & Mr Jones.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F04%20-%20Just%20Friends.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/04 - Just Friends.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F05%20-%20Back%20To%20Black.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/05 - Back To Black.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F06%20-%20Love%20Is%20A%20Losing%20Game.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/06 - Love Is A Losing Game.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F07%20-%20Tears%20Dry%20On%20Their%20Own.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/07 - Tears Dry On Their Own.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F08%20-%20Wake%20Up%20Alone.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/08 - Wake Up Alone.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F09%20-%20Some%20Unholy%20War.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/09 - Some Unholy War.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F10%20-%20He%20Can%20Only%20Hold%20Her.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/10 - He Can Only Hold Her.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2F11%20-%20Addicted.flac&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/11 - Addicted.flac Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2FBack%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%20-%20flac.cue&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/Back To Black Deluxe Edition [Disc 3] - flac.cue Oct 11 18:55:31 living-room volumio[1771]: info: ALBUMART /albumart?cacheid=138&web=Amy%20Winehouse/Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%201%5D/extralarge&path=%2Fmnt%2FUSB%2FBFEC-3C19%2F%5B0%20new%2FAmy%20Winehouse%20-%20Back%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%2FBack%20To%20Black%20Deluxe%20Edition%20%5BDisc%203%5D%20-%20flac.cue&metadata=false Oct 11 18:55:31 living-room volumio[1771]: info: URI /mnt/USB/BFEC-3C19/[0 new/Amy Winehouse - Back To Black Deluxe Edition [Disc 3]/Back To Black Deluxe Edition [Disc 3] - flac.cue Oct 11 18:55:31 living-room volumio[1771]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 18:55:31 living-room volumio[1771]: Error: Unable to resolve or reject the same promise twice Oct 11 18:55:31 living-room volumio[1771]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 11 18:55:31 living-room volumio[1771]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 11 18:55:31 living-room volumio[1771]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 11 18:55:31 living-room volumio[1771]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 11 18:55:31 living-room volumio[1771]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 11 18:55:31 living-room volumio[1771]: at Socket.emit (node:events:514:28) Oct 11 18:55:31 living-room volumio[1771]: at addChunk (node:internal/streams/readable:343:12) Oct 11 18:55:31 living-room volumio[1771]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 11 18:55:31 living-room volumio[1771]: at Readable.push (node:internal/streams/readable:253:10) Oct 11 18:55:31 living-room volumio[1771]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 11 18:55:31 living-room volumio[1771]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 18:55:31 living-room sudo[6933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 18:54' Oct 11 18:55:31 living-room sudo[6933]: 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"