Oct 02 14:04:25 volumio ntpd[1220]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 02 14:04:25 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:04:25 volumio volumio[1413]: info: CURURI: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD] Oct 02 14:04:25 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:04:34 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:04:34 volumio volumio[1413]: info: CURURI: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue Oct 02 14:04:34 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0001 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0001 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0002 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0002 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0003 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0003 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0004 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0004 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0005 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0005 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0006 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0006 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0007 Oct 02 14:04:34 volumio volumio[1413]: info: Preloading song: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0007 Oct 02 14:04:34 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0001 in service mpd Oct 02 14:04:34 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0001' Oct 02 14:04:34 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0001 Oct 02 14:04:34 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0002 in service mpd Oct 02 14:04:34 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0002' Oct 02 14:04:34 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0002 Oct 02 14:04:35 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0003 in service mpd Oct 02 14:04:35 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0003' Oct 02 14:04:35 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0003 Oct 02 14:04:35 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0004 in service mpd Oct 02 14:04:35 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0004' Oct 02 14:04:35 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0004 Oct 02 14:04:35 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0005 in service mpd Oct 02 14:04:35 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0005' Oct 02 14:04:35 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0005 Oct 02 14:04:35 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0006 in service mpd Oct 02 14:04:35 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0006' Oct 02 14:04:35 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0006 Oct 02 14:04:35 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0007 in service mpd Oct 02 14:04:35 volumio volumio[1413]: error: scanFolder - failure to stat '/mnt/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0007' Oct 02 14:04:35 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue/track0007 Oct 02 14:04:38 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::ClearQueue Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 21 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::serviceStop Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 21 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::serviceStop Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::stop Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::clearPlayQueue Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::saveQueue Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::addQueueItems Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::addQueueItems Oct 02 14:04:38 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:04:38 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue Oct 02 14:04:38 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue in service mpd Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.512+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.512+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="mnt/INTERNAL/Pink Floyd - Delicate Sound Of Thunder -2020 DSD/22 - One Slip.dsf" title="One Slip" Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::saveQueue Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPlay Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::play index 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::play index undefined Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand stop took 69 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand stop took 50 milliseconds Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:38 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand clear took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: 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 02 14:04:38 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: No code Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.570+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.571+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.571+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.571+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.572+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.572+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 8ms Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:38 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: error: updateQueue error: null Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:38 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 8ms Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" took 8 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand play Oct 02 14:04:38 volumio volumio[1413]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:38 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:38 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 5 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand play took 4 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 4ms Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 4ms Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: 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 02 14:04:38 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: No code Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.585+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.586+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.587+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.587+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.588+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:38 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:38.588+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 16ms Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 385 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 385 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 385 milliseconds Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: Oct 02 14:04:38 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:38 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:38 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"292 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:38 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":233,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1741 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:38 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:38 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1731 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:38 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:38 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:38 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:38 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:38 volumio volumio[1413]: info: ------------------------------ 391ms Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.001+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=233 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.001+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.001+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=233 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.001+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.002+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=285 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.002+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio[1413]: info: ------------------------------ 394ms Oct 02 14:04:39 volumio volumio[1413]: info: ------------------------------ 395ms Oct 02 14:04:39 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 14:04:39 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 02 14:04:39 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 02 14:04:39 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:39 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:39 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:39 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1746 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:39 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:39 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:39 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1746 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:39 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:39 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:39 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1746 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:39 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:39 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.006+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=285 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.006+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.009+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=992 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.009+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.010+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=992 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.010+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=992 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.010+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=992 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.010+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.010+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.010+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.011+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=992 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.011+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.011+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=992 volume=100 Oct 02 14:04:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:39.011+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:39 volumio volumio[1413]: info: ------------------------------ 21ms Oct 02 14:04:39 volumio volumio[1413]: info: ------------------------------ 20ms Oct 02 14:04:39 volumio volumio[1413]: info: ------------------------------ 19ms Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioGetState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioSeek Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::seek Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: TRACKBLOCK {"uri":"cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0","service":"mpd","name":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","number":1,"albumart":"/albumart?cacheid=796&path=%2Fmnt%2F%2FINTERNAL%2FEnigma%20-%20(1990)%20-%20MCMXC%20a.D.%20%5BSACD%5D&metadata=false","duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2} Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::seek Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.871+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.872+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio[1413]: error: null Oct 02 14:04:44 volumio volumio[1413]: info: Oct 02 14:04:44 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:44 volumio volumio[1413]: info: Oct 02 14:04:44 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:44 volumio volumio[1413]: info: Oct 02 14:04:44 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:44 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:44 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:44 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:44 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1944 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:44 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:44 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.878+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.878+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.878+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.878+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:04:44 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 02 14:04:44 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:44 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11855,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1953 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:44 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:44 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11992,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1957 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:44 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:44 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:44 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:44 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.883+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11855 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.883+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11855 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.883+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.883+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.884+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11992 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.884+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.884+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11992 volume=100 Oct 02 14:04:44 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:44.884+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:44 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:44 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioSeek Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::seek Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: TRACKBLOCK {"uri":"cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0","service":"mpd","name":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","number":1,"albumart":"/albumart?cacheid=796&path=%2Fmnt%2F%2FINTERNAL%2FEnigma%20-%20(1990)%20-%20MCMXC%20a.D.%20%5BSACD%5D&metadata=false","duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2} Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::seek Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.765+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.765+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio[1413]: error: null Oct 02 14:04:46 volumio volumio[1413]: info: Oct 02 14:04:46 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:46 volumio volumio[1413]: info: Oct 02 14:04:46 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:46 volumio volumio[1413]: info: Oct 02 14:04:46 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:46 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:46 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:46 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:46 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:04:46 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2012 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:46 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:46 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2012 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:46 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:46 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.834+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio[1413]: info: ------------------------------ 7ms Oct 02 14:04:46 volumio volumio[1413]: info: ------------------------------ 7ms Oct 02 14:04:46 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 02 14:04:46 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:46 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2012 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:46 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.836+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.836+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.836+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.836+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.836+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.837+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.837+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:46 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:46 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.839+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.839+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.839+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=23000 volume=100 Oct 02 14:04:46 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:46.840+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:46 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioSeek Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::seek Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: TRACKBLOCK {"uri":"cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0","service":"mpd","name":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","number":1,"albumart":"/albumart?cacheid=796&path=%2Fmnt%2F%2FINTERNAL%2FEnigma%20-%20(1990)%20-%20MCMXC%20a.D.%20%5BSACD%5D&metadata=false","duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2} Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::seek Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.877+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.877+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:47 volumio volumio[1413]: error: null Oct 02 14:04:47 volumio volumio[1413]: info: Oct 02 14:04:47 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:47 volumio volumio[1413]: info: Oct 02 14:04:47 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:47 volumio volumio[1413]: info: Oct 02 14:04:47 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:47 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:47 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:47 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:47 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:04:47 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:47 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:47 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2276 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:47 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:47 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2276 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:47 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:47 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2276 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:47 volumio volumio[1413]: verbose: CURRENT POSITION 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:47 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:47 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:47 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.941+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.941+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.942+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.942+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.942+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.942+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.942+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.943+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.943+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.943+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.943+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:47 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:47 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:47 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:47 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:47.946+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioNext Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::next Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::serviceStop Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 0 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::serviceStop Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::stop Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.055+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.056+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@0" title="The Voice Of Enigma" Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand stop took 62 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::play index undefined Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand stop took 1 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand clear took 0 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:49 volumio volumio[1413]: verbose: CURRENT POSITION 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: No code Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.126+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=1 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.126+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=1 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.126+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.126+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.126+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=1 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.126+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 9ms Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:49 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:49 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" took 7 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand play Oct 02 14:04:49 volumio volumio[1413]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 8ms Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:49 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:49 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 3ms Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 3ms Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand play took 3 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:49 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:49 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":703,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:49 volumio volumio[1413]: verbose: CURRENT POSITION 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: info: Oct 02 14:04:49 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":703,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:49 volumio volumio[1413]: verbose: CURRENT POSITION 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:49 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.137+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.137+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.137+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.138+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 7ms Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 6 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand status took 6 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 653 milliseconds Oct 02 14:04:49 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 653 milliseconds Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":703,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:49 volumio volumio[1413]: verbose: CURRENT POSITION 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:49 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":703,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:49 volumio volumio[1413]: verbose: CURRENT POSITION 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:49 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:49 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:49 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:49.797+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 666ms Oct 02 14:04:49 volumio volumio[1413]: info: ------------------------------ 666ms Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioNext Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::next Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::serviceStop Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 1 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::serviceStop Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::stop Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.008+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.008+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@1" title="Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley" Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand stop took 59 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::play index undefined Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand stop took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:54 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:54 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:54 volumio volumio[1413]: error: updateQueue error: null Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand clear took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Principles Of Lust: Sadeness / Find Love / Sadeness (Reprise) - Medley","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: No code Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: 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 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: No code Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.079+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.080+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.080+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.080+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.080+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.080+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 16ms Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 14ms Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:54 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:54 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:54 volumio volumio[1413]: error: updateQueue error: null Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:54 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 14ms Oct 02 14:04:54 volumio volumio[1413]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" took 14 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 17ms Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand play Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:04:54 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand play took 4 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 4ms Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: info: Oct 02 14:04:54 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:54 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:54 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 6ms Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.095+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.096+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 12ms Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 12ms Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 02 14:04:54 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:54 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:54 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:54 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:54 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:54 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:54 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.110+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.112+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.112+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.111+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:54.112+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 24ms Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 24ms Oct 02 14:04:54 volumio volumio[1413]: info: ------------------------------ 24ms Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioSeek Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::seek Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: TRACKBLOCK {"uri":"cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2","service":"mpd","name":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","number":3,"albumart":"/albumart?cacheid=796&path=%2Fmnt%2F%2FINTERNAL%2FEnigma%20-%20(1990)%20-%20MCMXC%20a.D.%20%5BSACD%5D&metadata=false","duration":269,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2} Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::seek Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.099+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.100+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio[1413]: error: null Oct 02 14:04:57 volumio volumio[1413]: info: Oct 02 14:04:57 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:57 volumio volumio[1413]: info: Oct 02 14:04:57 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:57 volumio volumio[1413]: info: Oct 02 14:04:57 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:57 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:04:57 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:57 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:57 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:57 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:57 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:57 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:57 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:57 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:57 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:57 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:57 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:57 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:57 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:57 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.107+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.107+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.108+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.108+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.108+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.108+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.109+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.109+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.110+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.110+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=11000 volume=100 Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.110+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:57.110+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:57 volumio volumio[1413]: info: ------------------------------ 11ms Oct 02 14:04:57 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:04:57 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioSeek Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::seek Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: TRACKBLOCK {"uri":"cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2","service":"mpd","name":"Callas Went Away","artist":"Enigma","album":"MCMXC a.D.","number":3,"albumart":"/albumart?cacheid=796&path=%2Fmnt%2F%2FINTERNAL%2FEnigma%20-%20(1990)%20-%20MCMXC%20a.D.%20%5BSACD%5D&metadata=false","duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2} Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::seek Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.549+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.550+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:58 volumio volumio[1413]: error: null Oct 02 14:04:58 volumio volumio[1413]: info: Oct 02 14:04:58 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:58 volumio volumio[1413]: info: Oct 02 14:04:58 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:58 volumio volumio[1413]: info: Oct 02 14:04:58 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:04:58 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:58 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:58 volumio volumio[1413]: info: sendMpdCommand status took 2 milliseconds Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:04:58 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:58 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:58 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:58 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2003 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:58 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:58 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2003 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:58 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:58 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37000,"duration":141,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2003 Kbps","isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:04:58 volumio volumio[1413]: verbose: CURRENT POSITION 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:04:58 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:04:58 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:04:58 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.608+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.608+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.609+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.609+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:58 volumio volumio[1413]: info: ------------------------------ 17ms Oct 02 14:04:58 volumio volumio[1413]: info: ------------------------------ 16ms Oct 02 14:04:58 volumio volumio[1413]: info: ------------------------------ 16ms Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=37000 volume=100 Oct 02 14:04:58 volumio volumio5-onboarding[2318]: time=2026-10-02T14:04:58.613+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioNext Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::next Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::serviceStop Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 2 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::serviceStop Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::stop Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.046+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.047+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@2" title="Callas Went Away" Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand stop took 60 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::play index undefined Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand stop took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand clear took 0 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice Of Enigma","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:00 volumio volumio[1413]: verbose: CURRENT POSITION 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: No code Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.114+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.114+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.114+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.114+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.114+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.114+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 8ms Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:00 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:00 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" took 6 milliseconds Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand play Oct 02 14:05:00 volumio volumio[1413]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 8ms Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:00 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:00 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand play took 2 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:00 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:00 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: Oct 02 14:05:00 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 0ms Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":301,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mea Culpa","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:00 volumio volumio[1413]: verbose: CURRENT POSITION 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 3ms Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":301,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mea Culpa","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:00 volumio volumio[1413]: verbose: CURRENT POSITION 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:05:00 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":301,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mea Culpa","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:00 volumio volumio[1413]: verbose: CURRENT POSITION 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:05:00 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":301,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mea Culpa","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:00 volumio volumio[1413]: verbose: CURRENT POSITION 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:05:00 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:00 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:00 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.133+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.133+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.135+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.135+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.135+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.135+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.136+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.136+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.136+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.136+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 19ms Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 20ms Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.138+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:00 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:00.138+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:00 volumio volumio[1413]: info: ------------------------------ 20ms Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioNext Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::next Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::serviceStop Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::serviceStop Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::stop Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.445+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.445+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@3" title="Mea Culpa" Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand stop took 60 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::play index undefined Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand stop took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:04 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand clear took 0 milliseconds Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: verbose: STATE SERVICE {"status":"stop","position":3,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mea Culpa","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:04 volumio volumio[1413]: verbose: CURRENT POSITION 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState stateService stop Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: No code Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.509+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.509+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.510+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.510+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.510+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.510+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:05:04 volumio volumio[1413]: error: updateQueue error: null Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:04 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:04 volumio volumio[1413]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Oct 02 14:05:04 volumio volumio[1413]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 4ms Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand load "INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue" took 4 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 5ms Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand play Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:04 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:04 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand play took 2 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:04 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces system playlist update Oct 02 14:05:04 volumio volumio[1413]: info: Ignoring MPD Status Update Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 1ms Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: Oct 02 14:05:04 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::getState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 0ms Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 0 milliseconds Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":101,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice And The Snake","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:04 volumio volumio[1413]: verbose: CURRENT POSITION 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 2ms Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand status took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseState Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":101,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice And The Snake","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:04 volumio volumio[1413]: verbose: CURRENT POSITION 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:05:04 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.517+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.517+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.517+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.517+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 4ms Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 14:05:04 volumio volumio[1413]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:04 volumio volumio[1413]: verbose: ControllerMpd::parseTrackInfo Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":101,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice And The Snake","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:04 volumio volumio[1413]: verbose: CURRENT POSITION 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:05:04 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: ControllerMpd::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::servicePushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":101,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Voice And The Snake","artist":"Enigma","album":"MCMXC a.D.","uri":"INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.flac","trackType":"flac"} Oct 02 14:05:04 volumio volumio[1413]: verbose: CURRENT POSITION 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState stateService play Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::syncState currentStatus play Oct 02 14:05:04 volumio volumio[1413]: info: Received an update from plugin. extracting info from payload Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:04 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:04 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.521+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.521+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.522+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.522+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.522+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.522+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:05:04 volumio volumio[1413]: info: ------------------------------ 10ms Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.524+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_PLAYING positionMs=0 volume=100 Oct 02 14:05:04 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:04.524+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:22 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:05:22 volumio volumio[1413]: info: CURURI: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD] Oct 02 14:05:22 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:05:26 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:05:26 volumio volumio[1413]: info: CURURI: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue Oct 02 14:05:26 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:05:31 volumio wpa_supplicant[1351]: wlan0: WPA: Group rekeying completed with f4:de:af:e8:bb:c0 [GTK=CCMP] Oct 02 14:05:39 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::ClearQueue Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::stPlaybackTimer Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::pushState Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushState Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::serviceStop Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::getTrack 4 Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::serviceStop Oct 02 14:05:39 volumio volumio[1413]: info: ControllerMpd::stop Oct 02 14:05:39 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::clearPlayQueue Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::saveQueue Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::addQueueItems Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::addQueueItems Oct 02 14:05:39 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Depeche Mode - Home.wav Oct 02 14:05:39 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Depeche Mode - Home.wav Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Raye – Regardless.flac Oct 02 14:05:39 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Raye – Regardless.flac Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Depeche Mode – Everything Counts (Original 7 Inch Mix).flac Oct 02 14:05:39 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Depeche Mode – Everything Counts (Original 7 Inch Mix).flac Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Elton John – Blessed.flac Oct 02 14:05:39 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Elton John – Blessed.flac Oct 02 14:05:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:39.107+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" state=STATUS_STOPPED positionMs=0 volume=100 Oct 02 14:05:39 volumio volumio5-onboarding[2318]: time=2026-10-02T14:05:39.107+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.214:59365,00:00:00:00:00:00%03 @ 0xc000317b90" id="cue://INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD]/Enigma - (1990) - MCMXC a.D. 2.0.cue@4" title="The Voice And The Snake" Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPushQueue Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::saveQueue Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::updateTrackBlock Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::getTrackBlock Oct 02 14:05:39 volumio volumio[1413]: info: CoreCommandRouter::volumioPlay Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::play index 3 Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:39 volumio volumio[1413]: info: CoreStateMachine::addQueueItems Oct 02 14:05:39 volumio volumio[1413]: info: CorePlayQueue::addQueueItems Oct 02 14:05:39 volumio volumio[1413]: info: Preload queue cleared Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/11. Money For Nothing.flac Oct 02 14:05:39 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/11. Money For Nothing.flac Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Peter and the Roses – Night Road.flac Oct 02 14:05:39 volumio volumio[1413]: info: Using cached record of: music-library/INTERNAL/Peter and the Roses – Night Road.flac Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/A-Ha Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/A-Ha in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Adele Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Adele in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Alphaville Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Alphaville in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Black Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Black in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Blue Coast Collection Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Blue Coast Collection in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD] Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Enigma - (1990) - MCMXC a.D. [SACD] in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Frank Duval Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Frank Duval in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Joe Dassin - Eternel... (2005, Re2023, France, 2LP, DST128) Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Joe Dassin - Eternel... (2005, Re2023, France, 2LP, DST128) in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Master Superior Audiophile 2020 [DSF] Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Master Superior Audiophile 2020 [DSF] in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Metallica - 72 Seasons (2023, EU) Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Metallica - 72 Seasons (2023, EU) in service mpd Oct 02 14:05:39 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/NEW Oct 02 14:05:39 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/NEW in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Pink Floyd - Delicate Sound Of Thunder -2020 DSD Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Pink Floyd - Delicate Sound Of Thunder -2020 DSD in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Pink_Floyd_-_The_Dark_Side_Of_The_Moon_50th_Anniversary__2023 Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Pink_Floyd_-_The_Dark_Side_Of_The_Moon_50th_Anniversary__2023 in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Pink_Floyd_at_Pompeii_-_MCMLXXII_2025 Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Pink_Floyd_at_Pompeii_-_MCMLXXII_2025 in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Planet of Rhythm Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Planet of Rhythm in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Polarity - An Acoustic Jazz Project Stereo Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Polarity - An Acoustic Jazz Project Stereo in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Richard Clayderman ‎– The Music Of Love- 1984,(UK),DSF(tracks),(ART-9s+NG-5rd+Tang) Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Richard Clayderman ‎– The Music Of Love- 1984,(UK),DSF(tracks),(ART-9s+NG-5rd+Tang) in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Scorpions Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Scorpions in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Space Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Space in service mpd Oct 02 14:05:40 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/VA - My Favourite Hits of 1991 [32CD] (2018) FLAC от DON Music Oct 02 14:05:40 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/VA - My Favourite Hits of 1991 [32CD] (2018) FLAC от DON Music in service mpd Oct 02 14:05:41 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/VA - My Favourite Hits of 1992 [22CD] (2019) FLAC от DON Music Oct 02 14:05:41 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/VA - My Favourite Hits of 1992 [22CD] (2019) FLAC от DON Music in service mpd Oct 02 14:05:41 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/VA - Vintage Cafe Collection [Lounge & Jazz Blends] FLAC Oct 02 14:05:41 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/VA - Vintage Cafe Collection [Lounge & Jazz Blends] FLAC in service mpd Oct 02 14:05:41 volumio volumio[1413]: info: Adding Item to queue: music-library/INTERNAL/Various Artists - The Sound of Jazz (1957-2014) [DSD128] Oct 02 14:05:41 volumio volumio[1413]: info: Exploding uri music-library/INTERNAL/Various Artists - The Sound of Jazz (1957-2014) [DSD128] in service mpd Oct 02 14:05:41 volumio volumio[1413]: info: CoreStateMachine::stop Oct 02 14:05:41 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:41 volumio volumio[1413]: info: CoreStateMachine::play index undefined Oct 02 14:05:41 volumio volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 14:05:41 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:41 volumio volumio[1413]: info: CoreStateMachine::startPlaybackTimer Oct 02 14:05:41 volumio volumio[1413]: info: CorePlayQueue::getTrack 3 Oct 02 14:05:41 volumio volumio[1413]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Elton John – Blessed.flac Oct 02 14:05:41 volumio volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 14:05:41 volumio volumio[1413]: info: Oct 02 14:05:41 volumio volumio[1413]: ---------------------------- MPD announces state update: player Oct 02 14:05:41 volumio volumio[1413]: info: ALBUMART /albumart?cacheid=796&web=A-Ha/Hunting%20High%20And%20Low%20(Warner%20Bros.%20Records%2C%20P-13153%2C%20Japan)/extralarge&path=%2Fmnt%2FINTERNAL%2FA-Ha%2F1985%20Hunting%20High%20And%20Low%2FA-Ha%20-%20Hunting%20High%20And%20Low.cue&metadata=false Oct 02 14:05:41 volumio volumio[1413]: info: URI /mnt/INTERNAL/A-Ha/1985 Hunting High And Low/A-Ha - Hunting High And Low.cue Oct 02 14:05:41 volumio volumio[1413]: info: ALBUMART /albumart?cacheid=796&web=A-Ha/Hunting%20High%20And%20Low%20(Warner%20Bros.%20Records%2C%20P-13153%2C%20Japan)/extralarge&path=%2Fmnt%2FINTERNAL%2FA-Ha%2F1985%20Hunting%20High%20And%20Low%2FA-Ha%20-%20Hunting%20High%20And%20Low.cue&metadata=false Oct 02 14:05:41 volumio volumio[1413]: info: URI /mnt/INTERNAL/A-Ha/1985 Hunting High And Low/A-Ha - Hunting High And Low.cue Oct 02 14:05:41 volumio volumio[1413]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 14:05:41 volumio volumio[1413]: Error: Unable to resolve or reject the same promise twice Oct 02 14:05:41 volumio volumio[1413]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 02 14:05:41 volumio volumio[1413]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 02 14:05:41 volumio volumio[1413]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 02 14:05:41 volumio volumio[1413]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 02 14:05:41 volumio volumio[1413]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 02 14:05:41 volumio volumio[1413]: at Socket.emit (node:events:514:28) Oct 02 14:05:41 volumio volumio[1413]: at addChunk (node:internal/streams/readable:343:12) Oct 02 14:05:41 volumio volumio[1413]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 02 14:05:41 volumio volumio[1413]: at Readable.push (node:internal/streams/readable:253:10) Oct 02 14:05:41 volumio volumio[1413]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 02 14:05:41 volumio volumio[1413]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 14:05:41 volumio sudo[3599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 14:04' Oct 02 14:05:41 volumio sudo[3599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"