Aug 27 17:24:12 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:24:12 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up Aug 27 17:24:12 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:17 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:24:17 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128] Aug 27 17:24:17 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:30 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:30 volumio1 volumio[1300]: info: Aug 27 17:24:30 volumio1 volumio[1300]: [1787840670119] ---------------------------- Client requests add and Play Volumio CUE entry Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Aug 27 17:24:30 volumio1 volumio[1300]: info: Adding CUE individual entry: 0 INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioAddQueueItems Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::addQueueItems Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::addQueueItems Aug 27 17:24:30 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:30 volumio1 volumio[1300]: info: Adding Item to queue: cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0 Aug 27 17:24:30 volumio1 volumio[1300]: info: Exploding uri cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0 in service mpd Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPlay Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::play index 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::stop Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::updateTrackBlock Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrackBlock Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 3 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::serviceStop Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 3 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::serviceStop Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::stop Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.190+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushQueue Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::saveQueue Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::updateTrackBlock Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrackBlock Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.193+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="mnt/NAS/Grace Mahya - The Look Of Love (2006) [DSD64]/04 - Tennessee Waltz.dsf" title="Tennessee Waltz" Aug 27 17:24:30 volumio1 volumio[1300]: info: Aug 27 17:24:30 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand stop took 24 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::getState Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::play index undefined Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::startPlaybackTimer Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand status took 6 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand stop took 1 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::parseState Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 17:24:30 volumio1 volumio[1300]: info: Aug 27 17:24:30 volumio1 volumio[1300]: ---------------------------- MPD announces system playlist update Aug 27 17:24:30 volumio1 volumio[1300]: info: Ignoring MPD Status Update Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand clear took 1 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue" Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::servicePushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: 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":"Tennessee Waltz","artist":"Grace Mahya","album":"The Look Of Love","uri":"NAS/Grace Mahya - The Look Of Love (2006) [DSD64]/04 - Tennessee Waltz.dsf","trackType":"dsf"} Aug 27 17:24:30 volumio1 volumio[1300]: verbose: CURRENT POSITION 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::syncState stateService stop Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::syncState currentStatus stop Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:30 volumio1 volumio[1300]: info: No code Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.231+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.232+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.232+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.232+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.233+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.233+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:30 volumio1 volumio[1300]: info: ------------------------------ 26ms Aug 27 17:24:30 volumio1 volumio[1300]: error: updateQueue error: null Aug 27 17:24:30 volumio1 volumio[1300]: info: Aug 27 17:24:30 volumio1 volumio[1300]: ---------------------------- MPD announces system playlist update Aug 27 17:24:30 volumio1 volumio[1300]: info: Ignoring MPD Status Update Aug 27 17:24:30 volumio1 volumio[1300]: info: ------------------------------ 22ms Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand load "INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue" took 21 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand play Aug 27 17:24:30 volumio1 volumio[1300]: info: ------------------------------ 18ms Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand play took 15 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: info: Aug 27 17:24:30 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::getState Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:24:30 volumio1 volumio[1300]: info: Aug 27 17:24:30 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand status took 27 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::getState Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::parseState Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand status took 6 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::parseState Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::servicePushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":59,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Audiophile Speakers Test Presentation","artist":"Various Artists","album":"2xHD - Audiophile Speaker Set-Up","uri":"INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/01-Audiophile Speakers Test Presentation.dsf","trackType":"dsf"} Aug 27 17:24:30 volumio1 volumio[1300]: verbose: CURRENT POSITION 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::syncState stateService play Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::syncState currentStatus stop Aug 27 17:24:30 volumio1 volumio[1300]: info: ------------------------------ 35ms Aug 27 17:24:30 volumio1 volumio[1300]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 27 17:24:30 volumio1 volumio[1300]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:24:30 volumio1 volumio[1300]: info: ControllerMpd::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::servicePushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: verbose: STATE SERVICE {"status":"play","position":0,"seek":995,"duration":59,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Audiophile Speakers Test Presentation","artist":"Various Artists","album":"2xHD - Audiophile Speaker Set-Up","uri":"INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/01-Audiophile Speakers Test Presentation.dsf","trackType":"dsf"} Aug 27 17:24:30 volumio1 volumio[1300]: verbose: CURRENT POSITION 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::syncState stateService play Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:24:30 volumio1 volumio[1300]: info: Received an update from plugin. extracting info from payload Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:30 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:30 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.316+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_PLAYING positionMs=995 volume=100 Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.317+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.317+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_PLAYING positionMs=995 volume=100 Aug 27 17:24:30 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:30.317+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:30 volumio1 volumio[1300]: info: ------------------------------ 35ms Aug 27 17:24:44 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:24:44 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128] Aug 27 17:24:44 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:48 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:24:48 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit] Aug 27 17:24:48 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:49 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:49 volumio1 volumio[1300]: info: Aug 27 17:24:49 volumio1 volumio[1300]: [1787840689452] ---------------------------- Client requests add and Play Volumio CUE entry Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Aug 27 17:24:49 volumio1 volumio[1300]: info: Adding CUE individual entry: 0 INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioAddQueueItems Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::addQueueItems Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::addQueueItems Aug 27 17:24:49 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:24:49 volumio1 volumio[1300]: info: Adding Item to queue: cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0 Aug 27 17:24:49 volumio1 volumio[1300]: info: Exploding uri cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0 in service mpd Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPlay Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::play index 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::stop Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::updateTrackBlock Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrackBlock Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::serviceStop Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 11 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::serviceStop Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::stop Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.535+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.536+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushQueue Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::saveQueue Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::updateTrackBlock Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrackBlock Aug 27 17:24:49 volumio1 volumio[1300]: info: Aug 27 17:24:49 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand stop took 20 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::getState Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::play index undefined Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::startPlaybackTimer Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand status took 4 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand stop took 1 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::parseState Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 17:24:49 volumio1 volumio[1300]: info: Aug 27 17:24:49 volumio1 volumio[1300]: ---------------------------- MPD announces system playlist update Aug 27 17:24:49 volumio1 volumio[1300]: info: Ignoring MPD Status Update Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand clear took 3 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand load "INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue" Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::servicePushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: 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":"Audiophile Speakers Test Presentation","artist":"Various Artists","album":"2xHD - Audiophile Speaker Set-Up","uri":"INTERNAL/2xHD - Audiophile Speaker Set-Up/[DSD128]/01-Audiophile Speakers Test Presentation.dsf","trackType":"dsf"} Aug 27 17:24:49 volumio1 volumio[1300]: verbose: CURRENT POSITION 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::syncState stateService stop Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::syncState currentStatus stop Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:49 volumio1 volumio[1300]: info: No code Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.573+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.573+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.575+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.575+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.576+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.576+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:49 volumio1 volumio[1300]: info: ------------------------------ 25ms Aug 27 17:24:49 volumio1 volumio[1300]: error: updateQueue error: null Aug 27 17:24:49 volumio1 volumio[1300]: info: Aug 27 17:24:49 volumio1 volumio[1300]: ---------------------------- MPD announces system playlist update Aug 27 17:24:49 volumio1 volumio[1300]: info: Ignoring MPD Status Update Aug 27 17:24:49 volumio1 volumio[1300]: info: ------------------------------ 19ms Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand load "INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue" took 18 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand play Aug 27 17:24:49 volumio1 volumio[1300]: info: Aug 27 17:24:49 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:24:49 volumio1 volumio[1300]: info: ------------------------------ 16ms Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand play took 13 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::getState Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:24:49 volumio1 volumio[1300]: info: Aug 27 17:24:49 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand status took 25 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::getState Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand status Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::parseState Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand status took 3 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::parseState Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::servicePushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":59,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Audiophile Speakers Test Presentation","artist":"Various Artists","album":"2xHD - Audiophile Speaker Set-Up","uri":"INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/01-Audiophile Speakers Test Presentation.flac","trackType":"flac"} Aug 27 17:24:49 volumio1 volumio[1300]: verbose: CURRENT POSITION 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::syncState stateService play Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::syncState currentStatus stop Aug 27 17:24:49 volumio1 volumio[1300]: info: ------------------------------ 33ms Aug 27 17:24:49 volumio1 volumio[1300]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 27 17:24:49 volumio1 volumio[1300]: verbose: ControllerMpd::parseTrackInfo Aug 27 17:24:49 volumio1 volumio[1300]: info: ControllerMpd::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::servicePushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":59,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Audiophile Speakers Test Presentation","artist":"Various Artists","album":"2xHD - Audiophile Speaker Set-Up","uri":"INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/01-Audiophile Speakers Test Presentation.flac","trackType":"flac"} Aug 27 17:24:49 volumio1 volumio[1300]: verbose: CURRENT POSITION 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::syncState stateService play Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::syncState currentStatus play Aug 27 17:24:49 volumio1 volumio[1300]: info: Received an update from plugin. extracting info from payload Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:24:49 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:24:49 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.637+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.637+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.640+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 27 17:24:49 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:24:49.640+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:24:49 volumio1 volumio[1300]: info: ------------------------------ 22ms Aug 27 17:25:07 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:25:07 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/MusicScope Aug 27 17:25:07 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:25:10 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:25:10 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/MusicScope/png Aug 27 17:25:10 volumio1 volumio[1300]: error: Failed LSINFO: null Aug 27 17:25:10 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:25:14 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:25:14 volumio1 volumio[1300]: info: CURURI: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/MusicScope/txt Aug 27 17:25:14 volumio1 volumio[1300]: error: Failed LSINFO: null Aug 27 17:25:14 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:25:35 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::ClearQueue Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::stop Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::stPlaybackTimer Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::updateTrackBlock Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::getTrackBlock Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::pushState Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushState Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::serviceStop Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 12 Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::serviceStop Aug 27 17:25:35 volumio1 volumio[1300]: info: ControllerMpd::stop Aug 27 17:25:35 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::clearPlayQueue Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::saveQueue Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushQueue Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::addQueueItems Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::addQueueItems Aug 27 17:25:35 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/09. THE FOOL ON THE HILL.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: Using cached record of: music-library/INTERNAL/09. THE FOOL ON THE HILL.dsf Aug 27 17:25:35 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:25:35.627+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 27 17:25:35 volumio1 volumio5-onboarding[1707]: time=2026-08-27T17:25:35.628+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:63090 @ 0xc00030b9b0" id="cue://INTERNAL/2xHD - Audiophile Speaker Set-Up/[192kHz-24bit]/2xHD Audiophile Speaker Set-Up.cue@0" title="Audiophile Speakers Test Presentation" Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPushQueue Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::saveQueue Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::updateTrackBlock Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::getTrackBlock Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreCommandRouter::volumioPlay Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::play index 0 Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::addQueueItems Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::addQueueItems Aug 27 17:25:35 volumio1 volumio[1300]: info: Preload queue cleared Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/A1. Touch By Touch (Touch Mix).dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: Using cached record of: music-library/INTERNAL/A1. Touch By Touch (Touch Mix).dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/B1. Fire In The Night (Bonus Mix).dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: Using cached record of: music-library/INTERNAL/B1. Fire In The Night (Bonus Mix).dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/2xHD - Audiophile Hi-Res System Test Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/2xHD - Audiophile Hi-Res System Test in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/2xHD - Audiophile Speaker Set-Up in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/Bernard Lavilliers - Nuit d'Amore (1981, 2004)[SACD-DSF] Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/Bernard Lavilliers - Nuit d'Amore (1981, 2004)[SACD-DSF] in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/Genesis 1986 Invisible Touch (Japan) DSD128 Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/Genesis 1986 Invisible Touch (Japan) DSD128 in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/Made In Heaven (1995) Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/Made In Heaven (1995) in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/Ryuichi Sakamoto - The Best of 'Playing the Orchestra 2014' 2nd (2015-2016) [DSD128] Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/Ryuichi Sakamoto - The Best of 'Playing the Orchestra 2014' 2nd (2015-2016) [DSD128] in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/THELONIOUS MONK SEPTET - MONK'S MUSIC - (2022) (HDTT) [DSD64] Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/THELONIOUS MONK SEPTET - MONK'S MUSIC - (2022) (HDTT) [DSD64] in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: Adding Item to queue: music-library/INTERNAL/screenshots Aug 27 17:25:35 volumio1 volumio[1300]: info: Exploding uri music-library/INTERNAL/screenshots in service mpd Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::stop Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::play index undefined Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 0 Aug 27 17:25:35 volumio1 volumio[1300]: info: CoreStateMachine::startPlaybackTimer Aug 27 17:25:35 volumio1 volumio[1300]: info: CorePlayQueue::getTrack 0 Aug 27 17:25:35 volumio1 volumio[1300]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/09. THE FOOL ON THE HILL.dsf Aug 27 17:25:35 volumio1 volumio[1300]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 17:25:35 volumio1 volumio[1300]: info: Aug 27 17:25:35 volumio1 volumio[1300]: ---------------------------- MPD announces state update: player Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F01-Presentation.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/01-Presentation.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F02-Jazz%20Group%20Live.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/02-Jazz Group Live.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F03-12%20Guitars%2C%201%20Double-Bass.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/03-12 Guitars, 1 Double-Bass.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F04-Percussion%201.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/04-Percussion 1.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F05-Voice%20and%20Jazz%20Group.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/05-Voice and Jazz Group.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F06-Harp.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/06-Harp.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F07-Jazz%20Bass%20and%20Piano.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/07-Jazz Bass and Piano.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F08-Piano.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/08-Piano.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F09-Bassoon.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/09-Bassoon.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F10-Violin%20Concerto.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/10-Violin Concerto.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F11-Baroque%20Music.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/11-Baroque Music.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F12-Children%20Choir.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/12-Children Choir.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F13-Organ.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/13-Organ.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F14-Piano.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/14-Piano.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F15-Harpsichord.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/15-Harpsichord.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F16-Percussion%202%20-%20Djembe%20Drum.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/16-Percussion 2 - Djembe Drum.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F17-Percussion%203%20(To%20be%20played%20at%20medium%20level).dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/17-Percussion 3 (To be played at medium level).dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F18-Xylophone.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/18-Xylophone.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F19-Glockenspiel.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/19-Glockenspiel.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F20-Piano%2C%20Vibraphone.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/20-Piano, Vibraphone.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F21-Percussion%204.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/21-Percussion 4.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F22-Timpani.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/22-Timpani.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F23-Test%20Presentation%20-%20Channel%20Identification.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/23-Test Presentation - Channel Identification.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F24-Left%20Channel.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/24-Left Channel.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F25-Right%20Channel.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/25-Right Channel.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F26-Phasing%20Test-%20In-phase.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/26-Phasing Test- In-phase.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F27-Phasing%20Test-%20Out-of-phase.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/27-Phasing Test- Out-of-phase.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F28-Presentation%2C%20Sound%20perspective.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/28-Presentation, Sound perspective.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F29-Bells%20Center%206'.dsf&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/29-Bells Center 6'.dsf Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/2xHD%20Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F2xHD%20Audiophile%20Hi-Res%20System%20Test.cue&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/2xHD Audiophile Hi-Res System Test.cue Aug 27 17:25:35 volumio1 volumio[1300]: info: ALBUMART /albumart?cacheid=637&web=Various%20Artists/2xHD%20Audiophile%20Hi-Res%20System%20Test/extralarge&path=%2Fmnt%2FINTERNAL%2F2xHD%20-%20Audiophile%20Hi-Res%20System%20Test%2F2xHD%20Audiophile%20Hi-Res%20System%20Test.cue&metadata=false Aug 27 17:25:35 volumio1 volumio[1300]: info: URI /mnt/INTERNAL/2xHD - Audiophile Hi-Res System Test/2xHD Audiophile Hi-Res System Test.cue Aug 27 17:25:35 volumio1 volumio[1300]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:25:35 volumio1 volumio[1300]: Error: Unable to resolve or reject the same promise twice Aug 27 17:25:35 volumio1 volumio[1300]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 27 17:25:35 volumio1 volumio[1300]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 27 17:25:35 volumio1 volumio[1300]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 27 17:25:35 volumio1 volumio[1300]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 27 17:25:35 volumio1 volumio[1300]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 27 17:25:35 volumio1 volumio[1300]: at Socket.emit (node:events:514:28) Aug 27 17:25:35 volumio1 volumio[1300]: at addChunk (node:internal/streams/readable:343:12) Aug 27 17:25:35 volumio1 volumio[1300]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 27 17:25:35 volumio1 volumio[1300]: at Readable.push (node:internal/streams/readable:253:10) Aug 27 17:25:35 volumio1 volumio[1300]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 27 17:25:35 volumio1 volumio[1300]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:25:36 volumio1 sudo[17428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 17:24' Aug 27 17:25:36 volumio1 sudo[17428]: 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"