Oct 14 02:08:10 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:08:10 volumio volumio[3946]: info: CURURI: music-library Oct 14 02:08:10 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:12 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:08:12 volumio volumio[3946]: info: CURURI: music-library/USB Oct 14 02:08:12 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:12 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:08:12 volumio volumio[3946]: info: CURURI: music-library/USB/USB_AUDIO Oct 14 02:08:12 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:17 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:08:17 volumio volumio[3946]: info: CURURI: music-library/USB/USB_AUDIO/DSD_Audio Oct 14 02:08:17 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:21 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:08:21 volumio volumio[3946]: info: CURURI: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco Oct 14 02:08:22 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:22 volumio volumio[3946]: info: Preloading song: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff Oct 14 02:08:22 volumio volumio[3946]: info: Preloading song: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/02 - Short Tales of the Black Forest.dff Oct 14 02:08:22 volumio volumio[3946]: info: Preloading song: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/03 - Frevo Rasgado.dff Oct 14 02:08:22 volumio volumio[3946]: info: Preloading song: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff Oct 14 02:08:22 volumio volumio[3946]: info: Preloading song: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/05 - Guardian Angel.dff Oct 14 02:08:22 volumio volumio[3946]: info: Exploding uri music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff in service mpd Oct 14 02:08:22 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=//extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2FAl%20Di%20Meola%20-%20Friday%20Night%20In%20San%20Francisco%2F01%20-%20Mediterranean%20Sundance_Rio%20Ancho.dff&metadata=false Oct 14 02:08:22 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff Oct 14 02:08:22 volumio volumio[3946]: info: Exploding uri music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/02 - Short Tales of the Black Forest.dff in service mpd Oct 14 02:08:22 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=//extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2FAl%20Di%20Meola%20-%20Friday%20Night%20In%20San%20Francisco%2F02%20-%20Short%20Tales%20of%20the%20Black%20Forest.dff&metadata=false Oct 14 02:08:22 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/02 - Short Tales of the Black Forest.dff Oct 14 02:08:22 volumio volumio[3946]: info: Exploding uri music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/03 - Frevo Rasgado.dff in service mpd Oct 14 02:08:22 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=//extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2FAl%20Di%20Meola%20-%20Friday%20Night%20In%20San%20Francisco%2F03%20-%20Frevo%20Rasgado.dff&metadata=false Oct 14 02:08:22 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/03 - Frevo Rasgado.dff Oct 14 02:08:22 volumio volumio[3946]: info: Exploding uri music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff in service mpd Oct 14 02:08:22 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=//extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2FAl%20Di%20Meola%20-%20Friday%20Night%20In%20San%20Francisco%2F04%20-%20Fantasia%20Suite.dff&metadata=false Oct 14 02:08:22 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff Oct 14 02:08:22 volumio volumio[3946]: info: Exploding uri music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/05 - Guardian Angel.dff in service mpd Oct 14 02:08:22 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=//extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2FAl%20Di%20Meola%20-%20Friday%20Night%20In%20San%20Francisco%2F05%20-%20Guardian%20Angel.dff&metadata=false Oct 14 02:08:22 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/05 - Guardian Angel.dff Oct 14 02:08:42 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::ClearQueue Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::stop Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 4 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::serviceStop Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 4 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::serviceStop Oct 14 02:08:42 volumio volumio[3946]: info: ControllerMpd::stop Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::clearPlayQueue Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::saveQueue Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::addQueueItems Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::addQueueItems Oct 14 02:08:42 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:42 volumio volumio[3946]: info: Adding Item to queue: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff Oct 14 02:08:42 volumio volumio[3946]: info: Using cached record of: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff Oct 14 02:08:42 volumio volumio[3946]: info: Adding Item to queue: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/02 - Short Tales of the Black Forest.dff Oct 14 02:08:42 volumio volumio[3946]: info: Using cached record of: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/02 - Short Tales of the Black Forest.dff Oct 14 02:08:42 volumio volumio[3946]: info: Adding Item to queue: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/03 - Frevo Rasgado.dff Oct 14 02:08:42 volumio volumio[3946]: info: Using cached record of: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/03 - Frevo Rasgado.dff Oct 14 02:08:42 volumio volumio[3946]: info: Adding Item to queue: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff Oct 14 02:08:42 volumio volumio[3946]: info: Using cached record of: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.586Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.586Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.586Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/(uaoa) Traffic - John Barleycorn Must Die - 1970/05 - John Barleycorn.dsf" title="John Barleycorn" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.586Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/(uaoa) Traffic - John Barleycorn Must Die - 1970/05 - John Barleycorn.dsf" title="John Barleycorn" Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::saveQueue Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPlay Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::play index 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::addQueueItems Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::addQueueItems Oct 14 02:08:42 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:08:42 volumio volumio[3946]: info: Adding Item to queue: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/05 - Guardian Angel.dff Oct 14 02:08:42 volumio volumio[3946]: info: Using cached record of: music-library/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/05 - Guardian Angel.dff Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::stop Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::saveQueue Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::play index undefined Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand stop took 8 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand stop took 2 milliseconds Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:42 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:42 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:08:42 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand clear took 1 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:42 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: 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 14 02:08:42 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::syncState stateService stop Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio[3946]: info: No code Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.597Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.597Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.597Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.597Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.598Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.598Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.598Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.598Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.598Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 5ms Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:08:42 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.599Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.599Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:08:42 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.599Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio[3946]: error: updateQueue error: null Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:08:42 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand status took 6 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 5ms Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand add "USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" took 5 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:42 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:08:42 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: 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 14 02:08:42 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::syncState stateService stop Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio[3946]: info: No code Oct 14 02:08:42 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:42 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:42 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:42 volumio volumio[3946]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.602Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.602Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.602Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:42.603Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 10ms Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 11ms Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:08:42 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:08:42 volumio volumio[3946]: info: Oct 14 02:08:42 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:08:42 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 7ms Oct 14 02:08:42 volumio volumio[3946]: info: sendMpdCommand play took 6 milliseconds Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 2ms Oct 14 02:08:42 volumio volumio[3946]: info: ------------------------------ 2ms Oct 14 02:08:43 volumio volumio[3946]: info: Oct 14 02:08:43 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:43 volumio volumio[3946]: info: Oct 14 02:08:43 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:43 volumio volumio[3946]: info: Oct 14 02:08:43 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:43 volumio volumio[3946]: info: Oct 14 02:08:43 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand status took 0 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:43 volumio volumio[3946]: info: Oct 14 02:08:43 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:43 volumio volumio[3946]: info: Oct 14 02:08:43 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand status took 0 milliseconds Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":530,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:08:43 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:08:43 volumio volumio[3946]: info: ------------------------------ 2ms Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":530,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:08:43 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:08:43 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":530,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:08:43 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:08:43 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":530,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:08:43 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:08:43 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":530,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:08:43 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:08:43 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.043Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio[3946]: info: ------------------------------ 7ms Oct 14 02:08:43 volumio volumio[3946]: info: ------------------------------ 8ms Oct 14 02:08:43 volumio volumio[3946]: info: ------------------------------ 8ms Oct 14 02:08:43 volumio volumio[3946]: info: ------------------------------ 7ms Oct 14 02:08:43 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 14 02:08:43 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:08:43 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1243,"duration":530,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:08:43 volumio volumio[3946]: verbose: CURRENT POSITION 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:08:43 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:08:43 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:08:43 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.048Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1243 volume=100 Oct 14 02:08:43 volumio volumio[3946]: info: ------------------------------ 11ms Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:08:43 volumio volumio5-onboarding[2193]: time=2026-10-14T00:08:43.049Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:09:04 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 02:09:04 volumio volumio[3946]: info: CURURI: music-library/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks) Oct 14 02:09:04 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:07 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: [1791936547334] ---------------------------- Client requests add and Play Volumio CUE entry Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Oct 14 02:09:07 volumio volumio[3946]: info: Adding CUE individual entry: 0 USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::addQueueItems Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::addQueueItems Oct 14 02:09:07 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:07 volumio volumio[3946]: info: Adding Item to queue: cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0 Oct 14 02:09:07 volumio volumio[3946]: info: Exploding uri cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0 in service mpd Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioPlay Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::play index 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::stop Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::serviceStop Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 3 Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::serviceStop Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::stop Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.349Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.349Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.349Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.349Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff" title="04 - Fantasia Suite.dff" Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::saveQueue Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand stop took 6 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::play index undefined Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand stop took 1 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand clear took 0 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand load "USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue" Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: 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":"04 - Fantasia Suite.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/04 - Fantasia Suite.dff","trackType":"dff"} Oct 14 02:09:07 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::syncState stateService stop Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:07 volumio volumio[3946]: info: No code Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:07 volumio volumio[3946]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.356Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.356Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.356Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:07 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:07.357Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 4ms Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 4ms Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:07 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:07 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:07 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:07 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand load "USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue" took 4 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:09:07 volumio volumio[3946]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 6ms Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:07 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:07 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 2ms Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 2ms Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand play took 2 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: Oct 14 02:09:07 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 02:09:07 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:07 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:07 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:07 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:07 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:07 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":86,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:07 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:07 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:09:07 volumio volumio[3946]: info: ------------------------------ 5ms Oct 14 02:09:08 volumio volumio[3946]: info: sendMpdCommand status took 4 milliseconds Oct 14 02:09:08 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 02:09:08 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 02:09:08 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 02:09:08 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:08 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":86,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:08 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:08 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":86,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:08 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:08 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":86,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:08 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:08 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":86,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:08 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:08 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.009Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.009Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.009Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.009Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.009Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.010Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.011Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio[3946]: info: ------------------------------ 21ms Oct 14 02:09:08 volumio volumio[3946]: info: ------------------------------ 20ms Oct 14 02:09:08 volumio volumio[3946]: info: ------------------------------ 21ms Oct 14 02:09:08 volumio volumio[3946]: info: ------------------------------ 19ms Oct 14 02:09:08 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 14 02:09:08 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:08 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1371,"duration":86,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:08 volumio volumio[3946]: verbose: CURRENT POSITION 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:08 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:08 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:08 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=1371 volume=100 Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:08.025Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:08 volumio volumio[3946]: info: ------------------------------ 28ms Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioNext Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::next Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::serviceStop Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 5 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::serviceStop Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::stop Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.120Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.120Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.121Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.121Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0" title="Pigs On The Wing 1" Oct 14 02:09:22 volumio volumio[3946]: info: Oct 14 02:09:22 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand stop took 9 milliseconds Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::play index undefined Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 6 Oct 14 02:09:22 volumio volumio[3946]: info: Oct 14 02:09:22 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:22 volumio volumio[3946]: info: Oct 14 02:09:22 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand status took 0 milliseconds Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: 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":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:22 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::syncState stateService stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: No code Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: 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":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:22 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::syncState stateService stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: No code Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.135Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio[3946]: info: ------------------------------ 8ms Oct 14 02:09:22 volumio volumio[3946]: info: ------------------------------ 7ms Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 02:09:22 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:22 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: 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":"Pigs On The Wing 1","artist":"Pink Floyd","album":"Animals (2018 Remix)","uri":"USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf","trackType":"dsf"} Oct 14 02:09:22 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::syncState stateService stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio[3946]: info: No code Oct 14 02:09:22 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:22 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:22 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.140Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.141Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:22.141Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:22 volumio volumio[3946]: info: ------------------------------ 10ms Oct 14 02:09:26 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:26 volumio volumio[3946]: info: Oct 14 02:09:26 volumio volumio[3946]: [1791936566891] ---------------------------- Client requests add and Play Volumio CUE entry Oct 14 02:09:26 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Oct 14 02:09:26 volumio volumio[3946]: info: Adding CUE individual entry: 0 USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue Oct 14 02:09:26 volumio volumio[3946]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::addQueueItems Oct 14 02:09:26 volumio volumio[3946]: info: CorePlayQueue::addQueueItems Oct 14 02:09:26 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:26 volumio volumio[3946]: info: Adding Item to queue: cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0 Oct 14 02:09:26 volumio volumio[3946]: info: Exploding uri cue://USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue@0 in service mpd Oct 14 02:09:26 volumio volumio[3946]: info: CoreCommandRouter::volumioPlay Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::play index 6 Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::stop Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::play index undefined Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:26 volumio volumio[3946]: info: CorePlayQueue::getTrack 6 Oct 14 02:09:26 volumio volumio[3946]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:09:26 volumio volumio[3946]: info: CorePlayQueue::saveQueue Oct 14 02:09:26 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:09:26 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPlay Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::play index undefined Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::clearAddPlayTracks USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand stop took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:34 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand clear took 0 milliseconds Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand add "USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:34 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:34 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:34 volumio volumio[3946]: error: updateQueue error: null Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:34 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand add "USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 0ms Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:34 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces system playlist update Oct 14 02:09:34 volumio volumio[3946]: info: Ignoring MPD Status Update Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand play took 2 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 1ms Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand status took 4 milliseconds Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand status took 5 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand status took 5 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand status took 0 milliseconds Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":691,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Mediterranean Sundance_Rio Ancho.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff","trackType":"dff"} Oct 14 02:09:34 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 6ms Oct 14 02:09:34 volumio volumio[3946]: info: Oct 14 02:09:34 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseState Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":691,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Mediterranean Sundance_Rio Ancho.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff","trackType":"dff"} Oct 14 02:09:34 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:34 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":691,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Mediterranean Sundance_Rio Ancho.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff","trackType":"dff"} Oct 14 02:09:34 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:34 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":691,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Mediterranean Sundance_Rio Ancho.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff","trackType":"dff"} Oct 14 02:09:34 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:34 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.523Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 11ms Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 11ms Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 7ms Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 02:09:34 volumio volumio[3946]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:34 volumio volumio[3946]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":691,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Mediterranean Sundance_Rio Ancho.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff","trackType":"dff"} Oct 14 02:09:34 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:34 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.524Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: ControllerMpd::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::servicePushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":691,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Mediterranean Sundance_Rio Ancho.dff","artist":null,"album":null,"uri":"USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff","trackType":"dff"} Oct 14 02:09:34 volumio volumio[3946]: verbose: CURRENT POSITION 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState stateService play Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:09:34 volumio volumio[3946]: info: Received an update from plugin. extracting info from payload Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:34 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:34 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.531Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 11ms Oct 14 02:09:34 volumio volumio[3946]: info: ------------------------------ 12ms Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.530Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_PLAYING positionMs=0 volume=100 Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.532Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:34 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:34.532Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:44 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:44 volumio volumio[3946]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::ClearQueue Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::stop Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::updateTrackBlock Oct 14 02:09:44 volumio volumio[3946]: info: CorePlayQueue::getTrackBlock Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::pushState Oct 14 02:09:44 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:44 volumio volumio[3946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:09:44 volumio volumio[3946]: info: CoreCommandRouter::volumioPushState Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::serviceStop Oct 14 02:09:44 volumio volumio[3946]: info: CorePlayQueue::getTrack 0 Oct 14 02:09:44 volumio volumio[3946]: info: CoreCommandRouter::serviceStop Oct 14 02:09:44 volumio volumio[3946]: info: ControllerMpd::stop Oct 14 02:09:44 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:09:44 volumio volumio[3946]: info: CorePlayQueue::clearPlayQueue Oct 14 02:09:44 volumio volumio[3946]: info: CorePlayQueue::saveQueue Oct 14 02:09:44 volumio volumio[3946]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:09:44 volumio volumio[3946]: info: CoreStateMachine::addQueueItems Oct 14 02:09:44 volumio volumio[3946]: info: CorePlayQueue::addQueueItems Oct 14 02:09:44 volumio volumio[3946]: info: Preload queue cleared Oct 14 02:09:44 volumio volumio[3946]: info: Adding Item to queue: music-library/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks) Oct 14 02:09:44 volumio volumio[3946]: info: Exploding uri music-library/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks) in service mpd Oct 14 02:09:44 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:44.586Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:44 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:44.586Z level=INFO msg="emitting player state changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 02:09:44 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:44.586Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.178.139:35464 @ 0xc000484510" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:44 volumio volumio5-onboarding[2193]: time=2026-10-14T00:09:44.586Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.139:35464 @ 0xc00052fc80" id="mnt/USB/USB_AUDIO/DSD_Audio/Al Di Meola - Friday Night In San Francisco/01 - Mediterranean Sundance_Rio Ancho.dff" title="01 - Mediterranean Sundance_Rio Ancho.dff" Oct 14 02:09:44 volumio volumio[3946]: info: Oct 14 02:09:44 volumio volumio[3946]: ---------------------------- MPD announces state update: player Oct 14 02:09:44 volumio volumio[3946]: info: sendMpdCommand stop took 9 milliseconds Oct 14 02:09:44 volumio volumio[3946]: info: ControllerMpd::getState Oct 14 02:09:44 volumio volumio[3946]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2F01%20-%20Pigs%20On%20The%20Wing%201.dsf&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/01 - Pigs On The Wing 1.dsf Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2F02%20-%20Dogs.dsf&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/02 - Dogs.dsf Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2F03%20-%20Pigs%20(Three%20Different%20Ones).dsf&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/03 - Pigs (Three Different Ones).dsf Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2F04%20-%20Sheep.dsf&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/04 - Sheep.dsf Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2F05%20-%20Pigs%20On%20The%20Wing%202.dsf&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/05 - Pigs On The Wing 2.dsf Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2FAnimals%20(2018%20Remix).cue&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue Oct 14 02:09:44 volumio volumio[3946]: info: ALBUMART /albumart?cacheid=129&web=Pink%20Floyd/Animals%20(2018%20Remix)/extralarge&path=%2Fmnt%2FUSB%2FUSB_AUDIO%2FDSD_Audio%2F(Prog%20Rock)%20%5BLP%5D%20Pink%20Floyd%20-%20Animals%20(2018%20Remix)%20-%202022%2C%20DSD128%20(tracks)%2FAnimals%20(2018%20Remix).cue&metadata=false Oct 14 02:09:44 volumio volumio[3946]: info: URI /mnt/USB/USB_AUDIO/DSD_Audio/(Prog Rock) [LP] Pink Floyd - Animals (2018 Remix) - 2022, DSD128 (tracks)/Animals (2018 Remix).cue Oct 14 02:09:44 volumio volumio[3946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 02:09:44 volumio volumio[3946]: Error: Unable to resolve or reject the same promise twice Oct 14 02:09:44 volumio volumio[3946]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 14 02:09:44 volumio volumio[3946]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 14 02:09:44 volumio volumio[3946]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 14 02:09:44 volumio volumio[3946]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 14 02:09:44 volumio volumio[3946]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 14 02:09:44 volumio volumio[3946]: at Socket.emit (node:events:514:28) Oct 14 02:09:44 volumio volumio[3946]: at addChunk (node:internal/streams/readable:343:12) Oct 14 02:09:44 volumio volumio[3946]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 14 02:09:44 volumio volumio[3946]: at Readable.push (node:internal/streams/readable:253:10) Oct 14 02:09:44 volumio volumio[3946]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 14 02:09:44 volumio volumio[3946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 02:09:44 volumio sudo[9118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 02:08' Oct 14 02:09:44 volumio sudo[9118]: 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"