Feb 10 15:11:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 15:11:00 volumio volumio[1142]: info: Received Get System Info Feb 10 15:11:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 15:11:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 15:11:00 volumio volumio[1142]: info: Discovery: Getting this device information Feb 10 15:11:00 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Feb 10 15:11:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 15:11:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 15:11:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 15:11:03 volumio volumio[1142]: info: Discovery: Getting this device information Feb 10 15:11:03 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Feb 10 15:11:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 15:11:03 volumio volumio[1142]: verbose: New Socket.io Connection to 192.168.10.38:3000 from 192.168.10.5 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 10 15:11:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 10 15:11:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 10 15:11:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 10 15:11:08 volumio volumio[1142]: info: CURURI: music-library/NAS/FLAC Feb 10 15:11:08 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:11:08 volumio go-librespot[1510]: time="2026-02-10T15:11:08+09:00" level=trace msg="received accesspoint ping" Feb 10 15:11:08 volumio go-librespot[1510]: time="2026-02-10T15:11:08+09:00" level=trace msg="received accesspoint pong ack" Feb 10 15:11:09 volumio go-librespot[1510]: time="2026-02-10T15:11:09+09:00" level=trace msg="sent dealer ping" Feb 10 15:11:09 volumio go-librespot[1510]: time="2026-02-10T15:11:09+09:00" level=trace msg="received dealer pong" Feb 10 15:11:09 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 10 15:11:09 volumio volumio[1142]: info: CURURI: music-library/NAS/FLAC/Jazz Feb 10 15:11:09 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:11:39 volumio go-librespot[1510]: time="2026-02-10T15:11:39+09:00" level=trace msg="sent dealer ping" Feb 10 15:11:39 volumio go-librespot[1510]: time="2026-02-10T15:11:39+09:00" level=trace msg="received dealer pong" Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::serviceStop Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::stop Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Feb 10 15:11:42 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:11:42 volumio volumio[1142]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC] Feb 10 15:11:42 volumio volumio[1142]: info: Exploding uri music-library/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC] in service mpd Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.392+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.393+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/DSD/Jazz/The Great Jazz Trio - July 6th -Live at Birdland New York (2007) [SACD-DSF]/01 - Mercy,Mercy,Mercy.dsf" title="1 - Mercy,Mercy,Mercy" Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand stop took 53 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F01%20-%20Fingerprints.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F02%20-%20Silky%20Smooth.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/02 - Silky Smooth.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F03%20-%20The%20Storyteller.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/03 - The Storyteller.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F04%20-%20'Til%20I%20Hurt%20You%20(feat.%20Michael%20McDonald).flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/04 - 'Til I Hurt You (feat. Michael McDonald).flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F05%20-%20Slave%20Song.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/05 - Slave Song.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F06%20-%20All%20Thru%20the%20Night.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/06 - All Thru the Night.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F07%20-%20Lazy%20Susan.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/07 - Lazy Susan.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F08%20-%20Chicks%20with%20Kickstands.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/08 - Chicks with Kickstands.flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F09%20-%20Gracias%20(Guitar%20Duet%20with%20Vince%20Gill).flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/09 - Gracias (Guitar Duet with Vince Gill).flac Feb 10 15:11:42 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fingerprints/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fingerprints%20-%20(2000)%20%5BFLAC%5D%2F10%20-%20Crying%20Hands.flac&metadata=false Feb 10 15:11:42 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/10 - Crying Hands.flac Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::play index 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::play index undefined Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 13 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand stop took 1 milliseconds Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: 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":"Mercy,Mercy,Mercy","artist":"July 6th ~Live at BIRDLAND New York~","album":"July 6th ~Live at BIRDLAND New York~","uri":"NAS/DSD/Jazz/The Great Jazz Trio - July 6th -Live at Birdland New York (2007) [SACD-DSF]/01 - Mercy,Mercy,Mercy.dsf","trackType":"dsf"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: No code Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: 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":"Mercy,Mercy,Mercy","artist":"July 6th ~Live at BIRDLAND New York~","album":"July 6th ~Live at BIRDLAND New York~","uri":"NAS/DSD/Jazz/The Great Jazz Trio - July 6th -Live at Birdland New York (2007) [SACD-DSF]/01 - Mercy,Mercy,Mercy.dsf","trackType":"dsf"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: No code Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.417+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.417+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.417+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.417+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.418+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.418+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.419+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.419+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.419+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.419+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.419+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.419+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 25ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 25ms Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:42 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand clear took 14 milliseconds Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: 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":"Mercy,Mercy,Mercy","artist":"July 6th ~Live at BIRDLAND New York~","album":"July 6th ~Live at BIRDLAND New York~","uri":"NAS/DSD/Jazz/The Great Jazz Trio - July 6th -Live at Birdland New York (2007) [SACD-DSF]/01 - Mercy,Mercy,Mercy.dsf","trackType":"dsf"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: No code Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.430+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.430+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.430+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.430+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.430+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.430+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 35ms Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:42 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:42 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:42 volumio volumio[1142]: error: updateQueue error: null Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:42 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 15ms Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand add "NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" took 14 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 6ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 5ms Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:42 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:42 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 52ms Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand play took 52 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 51ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 51ms Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: Feb 10 15:11:42 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 5 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 5 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:42 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 14ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 14ms Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 15:11:42 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:42 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.591+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.591+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.591+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.591+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:42 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:42 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1004 Kbps","isStreaming":false,"title":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:42 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:42 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:42 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:42 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.611+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:11:42 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:42.612+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 35ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 34ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 33ms Feb 10 15:11:42 volumio volumio[1142]: info: ------------------------------ 34ms Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:42 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::serviceStop Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::stop Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Feb 10 15:11:53 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:11:53 volumio volumio[1142]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Larry Carlton - Fire Wire Feb 10 15:11:53 volumio volumio[1142]: info: Exploding uri music-library/NAS/FLAC/Jazz/Larry Carlton - Fire Wire in service mpd Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.637+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.638+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac" title="1 - Fingerprints" Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F01%20-%20inkblot%2011.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Firewire%20%5BBonus%20Track%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F02%20-%20double%20cross.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/02 - double cross.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F03%20-%20naked%20truth.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/03 - naked truth.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F04%20-%20surrender.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/04 - surrender.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F05%20-%20big%20trouble.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/05 - big trouble.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F06%20-%20goodbye.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/06 - goodbye.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F07%20-%20dirty%20donna's%20house%20party.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/07 - dirty donna's house party.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F08%20-%20the%20prince.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/08 - the prince.flac Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F09%20-%20sunrise.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/09 - sunrise.flac Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand stop took 49 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Fire%20Wire/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20Fire%20Wire%2F10%20-%20mean%20street.flac&metadata=false Feb 10 15:11:53 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/10 - mean street.flac Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::play index 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::play index undefined Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 5 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand stop took 3 milliseconds Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:53 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand clear took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: 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":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: No code Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: 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":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: No code Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: 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":"Fingerprints","artist":"Larry Carlton","album":"Fingerprints","uri":"NAS/FLAC/Jazz/Larry Carlton - Fingerprints - (2000) [FLAC]/01 - Fingerprints.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: No code Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.659+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.659+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.661+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.661+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.661+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.661+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.661+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.661+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.662+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 22ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 22ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 21ms Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.665+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.665+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:53 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:53 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:53 volumio volumio[1142]: error: updateQueue error: null Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:53 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 17ms Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand add "NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" took 16 milliseconds Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 4ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 5ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 5ms Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand play took 5 milliseconds Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:53 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:11:53 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 7ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 1ms Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 1 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: Feb 10 15:11:53 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 11ms Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 10 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand status took 9 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:53 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:53 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:53 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.735+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.735+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.736+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 27ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 27ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 27ms Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 10 15:11:53 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:53 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4 Kbps","isStreaming":false,"title":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:11:53 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:11:53 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:11:53 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:11:53 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.752+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.752+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.755+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.755+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.755+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.755+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.755+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:11:53 volumio volumio5-onboarding[1449]: time=2026-02-10T15:11:53.756+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 45ms Feb 10 15:11:53 volumio volumio[1142]: info: ------------------------------ 45ms Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:11:53 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::serviceStop Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::stop Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Feb 10 15:12:00 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:12:00 volumio volumio[1142]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Larry Carlton - last Nite Feb 10 15:12:00 volumio volumio[1142]: info: Exploding uri music-library/NAS/FLAC/Jazz/Larry Carlton - last Nite in service mpd Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.434+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.435+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac" title="1 - Inkblot 11" Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand stop took 19 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Last%20Nite/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20last%20Nite%2F1%20So%20What.flac&metadata=false Feb 10 15:12:00 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac Feb 10 15:12:00 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Last%20Nite/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20last%20Nite%2F2%20Don't%20Give%20It%20Up.flac&metadata=false Feb 10 15:12:00 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/2 Don't Give It Up.flac Feb 10 15:12:00 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Last%20Nite/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20last%20Nite%2F3%20The%20B.P.%20Blues.flac&metadata=false Feb 10 15:12:00 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/3 The B.P. Blues.flac Feb 10 15:12:00 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Last%20Nite/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20last%20Nite%2F4%20All%20Blues.flac&metadata=false Feb 10 15:12:00 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/4 All Blues.flac Feb 10 15:12:00 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Last%20Nite/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20last%20Nite%2F5%20Last%20Nite.flac&metadata=false Feb 10 15:12:00 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/5 Last Nite.flac Feb 10 15:12:00 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/Last%20Nite/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20last%20Nite%2F6%20Emotions%20Wound%20Us%20So.flac&metadata=false Feb 10 15:12:00 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/6 Emotions Wound Us So.flac Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPlay Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::play index 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::play index undefined Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::startPlaybackTimer Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 4 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand stop took 0 milliseconds Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:12:00 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand clear took 2 milliseconds Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: 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":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: No code Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: 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":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: No code Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: 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":"Inkblot 11","artist":"Larry Carlton","album":"Fire Wire","uri":"NAS/FLAC/Jazz/Larry Carlton - Fire Wire/01 - inkblot 11.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: No code Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.450+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.450+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.451+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.452+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.451+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.451+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.452+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.452+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.452+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.452+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.454+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 15ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 15ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 15ms Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:12:00 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:12:00 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:12:00 volumio volumio[1142]: error: updateQueue error: null Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:12:00 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 11ms Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand add "NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" took 10 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 0ms Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand play Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:12:00 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces system playlist update Feb 10 15:12:00 volumio volumio[1142]: info: Ignoring MPD Status Update Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 3ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 3ms Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand play took 7 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 7ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 7ms Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 10 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 10 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 11 milliseconds Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: Feb 10 15:12:00 volumio volumio[1142]: ---------------------------- MPD announces state update: player Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::getState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand status Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 8 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 3 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand status took 6 milliseconds Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseState Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"So What","artist":"Larry Carlton","album":"Last Nite","uri":"NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus stop Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"So What","artist":"Larry Carlton","album":"Last Nite","uri":"NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:12:00 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":478,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"So What","artist":"Larry Carlton","album":"Last Nite","uri":"NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:12:00 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 36ms Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.522+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.522+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.523+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.523+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.523+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.523+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.523+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=0 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.523+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 37ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 38ms Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 10 15:12:00 volumio volumio[1142]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: verbose: ControllerMpd::parseTrackInfo Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":478,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"So What","artist":"Larry Carlton","album":"Last Nite","uri":"NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:12:00 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":478,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"So What","artist":"Larry Carlton","album":"Last Nite","uri":"NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:12:00 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: ControllerMpd::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::servicePushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":478,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"So What","artist":"Larry Carlton","album":"Last Nite","uri":"NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac","trackType":"flac"} Feb 10 15:12:00 volumio volumio[1142]: verbose: CURRENT POSITION 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState stateService play Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::syncState currentStatus play Feb 10 15:12:00 volumio volumio[1142]: info: Received an update from plugin. extracting info from payload Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:00 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:00 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.548+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.549+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.551+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.551+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.551+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_PLAYING positionMs=844 volume=100 Feb 10 15:12:00 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:00.551+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 58ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 54ms Feb 10 15:12:00 volumio volumio[1142]: info: ------------------------------ 53ms Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:00 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:09 volumio go-librespot[1510]: time="2026-02-10T15:12:09+09:00" level=trace msg="sent dealer ping" Feb 10 15:12:09 volumio go-librespot[1510]: time="2026-02-10T15:12:09+09:00" level=trace msg="received dealer pong" Feb 10 15:12:17 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 10 15:12:17 volumio volumio[1142]: info: CURURI: music-library/NAS/FLAC/Jazz/Larry Young - In Paris The ORTF Recordings (1964) [FLAC] Feb 10 15:12:17 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:12:18 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 10 15:12:18 volumio volumio[1142]: info: CURURI: music-library/NAS/FLAC/Jazz/Larry Young - In Paris The ORTF Recordings (1964) [FLAC]/disc 01 Feb 10 15:12:18 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:12:32 volumio volumio[1142]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::ClearQueue Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::stop Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::stPlaybackTimer Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Feb 10 15:12:32 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::pushState Feb 10 15:12:32 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:32 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 15:12:32 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::serviceStop Feb 10 15:12:32 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Feb 10 15:12:32 volumio volumio[1142]: info: CoreCommandRouter::serviceStop Feb 10 15:12:32 volumio volumio[1142]: info: ControllerMpd::stop Feb 10 15:12:32 volumio volumio[1142]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 15:12:32 volumio volumio[1142]: info: CorePlayQueue::clearPlayQueue Feb 10 15:12:32 volumio volumio[1142]: info: CorePlayQueue::saveQueue Feb 10 15:12:32 volumio volumio[1142]: info: CoreCommandRouter::volumioPushQueue Feb 10 15:12:32 volumio volumio[1142]: info: CoreStateMachine::addQueueItems Feb 10 15:12:32 volumio volumio[1142]: info: CorePlayQueue::addQueueItems Feb 10 15:12:32 volumio volumio[1142]: info: Preload queue cleared Feb 10 15:12:32 volumio volumio[1142]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Larry Carlton - The Gift Feb 10 15:12:32 volumio volumio[1142]: info: Exploding uri music-library/NAS/FLAC/Jazz/Larry Carlton - The Gift in service mpd Feb 10 15:12:32 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:32.371+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" state=STATUS_STOPPED positionMs=0 volume=100 Feb 10 15:12:32 volumio volumio5-onboarding[1449]: time=2026-02-10T15:12:32.371+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:46820 @ 0xc0002796b0" id="mnt/NAS/FLAC/Jazz/Larry Carlton - last Nite/1 So What.flac" title="1 - So What" Feb 10 15:12:32 volumio volumio[1142]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F01%20-%20Ridin'%20The%20Treasure.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/01 - Ridin' The Treasure.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F02%20-%20Things%20We%20Said%20Today.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/02 - Things We Said Today.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F03%20-%20Goin'%20Nowhere.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/03 - Goin' Nowhere.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F04%20-%20The%20Gift.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/04 - The Gift.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F05%20-%20Shop%20'Till%20You%20Drop.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/05 - Shop 'Till You Drop.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F06%20-%20Pammie%20Dear.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/06 - Pammie Dear.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F07%20-%20Osaka%20Cool.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/07 - Osaka Cool.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F08%20-%20My%20Old%20Town.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/08 - My Old Town.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F09%20-%20Mourning%20Dove.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/09 - Mourning Dove.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2F10%20-%20Buddy.flac&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/10 - Buddy.flac Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2FThe%20Gift.flac.cue&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/The Gift.flac.cue Feb 10 15:12:32 volumio volumio[1142]: info: ALBUMART /albumart?cacheid=271&web=Larry%20Carlton/The%20Gift/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FLarry%20Carlton%20-%20The%20Gift%2FThe%20Gift.flac.cue&metadata=false Feb 10 15:12:32 volumio volumio[1142]: info: URI /mnt/NAS/FLAC/Jazz/Larry Carlton - The Gift/The Gift.flac.cue Feb 10 15:12:32 volumio volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 15:12:32 volumio volumio[1142]: Error: Unable to resolve or reject the same promise twice Feb 10 15:12:32 volumio volumio[1142]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 10 15:12:32 volumio volumio[1142]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Feb 10 15:12:32 volumio volumio[1142]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 10 15:12:32 volumio volumio[1142]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 10 15:12:32 volumio volumio[1142]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 10 15:12:32 volumio volumio[1142]: at Socket.emit (node:events:514:28) Feb 10 15:12:32 volumio volumio[1142]: at addChunk (node:internal/streams/readable:343:12) Feb 10 15:12:32 volumio volumio[1142]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 10 15:12:32 volumio volumio[1142]: at Readable.push (node:internal/streams/readable:253:10) Feb 10 15:12:32 volumio volumio[1142]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 10 15:12:32 volumio volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 15:12:32 volumio sudo[32032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 15:11' Feb 10 15:12:32 volumio sudo[32032]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"