Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Preload queue cleared Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::ClearQueue Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::stPlaybackTimer Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::updateTrackBlock Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrackBlock Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::serviceStop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::serviceStop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::clearPlayQueue Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::saveQueue Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::addQueueItems Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::addQueueItems Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Preload queue cleared Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Adding Item to queue: music-library/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit) Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Exploding uri music-library/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit) in service mpd Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F01.%20Enter.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/01. Enter.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F02.%20Come.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/02. Come.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F03.%20Boundaries%20Are%20Open.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/03. Boundaries Are Open.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F04.%20Living%20Shields.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/04. Living Shields.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F05.%20Being%20Everyone.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/05. Being Everyone.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F06.%20Attendance.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/06. Attendance.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F07.%20Free%20of%20Doubt.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/07. Free of Doubt.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F08.%20Only%20Everything.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/08. Only Everything.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F09.%20Strong.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/09. Strong.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F10.%20Face%20Your%20Demons.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/10. Face Your Demons.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F11.%20No%20Control.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/11. No Control.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F12.%20Forever.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/12. Forever.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F13.%20Taste%20the%20Day%20(Remagine).flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/13. Taste the Day (Remagine).flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Feb 17 11:27:23 volumiorpi-5 volumio[1394]: ---------------------------- MPD announces state update: player Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F14.%20Live%20and%20Learn.flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/14. Live and Learn.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=After%20Forever/Remagine/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FAfter%20Forever%20-%202005%20-%20Remagine%20(5.1%20Multichannel%2024%20bit)%2F15.%20Strong%20(Piano%20version).flac&metadata=false Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/15. Strong (Piano version).flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand stop took 51 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::getState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::saveQueue Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::updateTrackBlock Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrackBlock Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPlay Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::play index 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::play index undefined Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::startPlaybackTimer Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::clearAddPlayTracks NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/01. Enter.flac Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand status took 16 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand stop took 8 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::parseState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Feb 17 11:27:23 volumiorpi-5 volumio[1394]: ---------------------------- MPD announces system playlist update Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Ignoring MPD Status Update Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand clear took 5 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand add "NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/01. Enter.flac" Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::servicePushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: 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":"Hearts AF","artist":"America","album":"1975 Hearts (Hybrid SACD Audio Fidelity 2016)","uri":"NAS/TrueNAS/America - Hearts/America - Hearts AF.flac","trackType":"flac"} Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: CURRENT POSITION 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::syncState stateService stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::syncState currentStatus stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: No code Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ------------------------------ 51ms Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 17 11:27:23 volumiorpi-5 volumio[1394]: error: updateQueue error: null Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Feb 17 11:27:23 volumiorpi-5 volumio[1394]: ---------------------------- MPD announces system playlist update Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Ignoring MPD Status Update Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ------------------------------ 30ms Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand add "NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/01. Enter.flac" took 28 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand play Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Feb 17 11:27:23 volumiorpi-5 volumio[1394]: ---------------------------- MPD announces state update: player Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ------------------------------ 41ms Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand play took 41 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::getState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Feb 17 11:27:23 volumiorpi-5 volumio[1394]: ---------------------------- MPD announces state update: player Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::getState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand status took 3 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::parseState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand status took 2 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::parseState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::servicePushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":66,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"2488 Kbps","isStreaming":false,"title":"Enter","artist":"After Forever","album":"Remagine","uri":"NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/01. Enter.flac","trackType":"flac"} Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: CURRENT POSITION 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::syncState stateService play Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::syncState currentStatus stop Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ------------------------------ 6ms Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ControllerMpd::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::servicePushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":66,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":6,"random":false,"updatedb":false,"repeat":false,"bitrate":"2488 Kbps","isStreaming":false,"title":"Enter","artist":"After Forever","album":"Remagine","uri":"NAS/TrueNAS/After Forever - 2005 - Remagine (5.1 Multichannel 24 bit)/01. Enter.flac","trackType":"flac"} Feb 17 11:27:23 volumiorpi-5 volumio[1394]: verbose: CURRENT POSITION 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::syncState stateService play Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: Received an update from plugin. extracting info from payload Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:23 volumiorpi-5 volumio[1394]: info: ------------------------------ 30ms Feb 17 11:27:25 volumiorpi-5 volumio[1394]: info: Executing endpoint metavolumio Feb 17 11:27:25 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 11:27:25 volumiorpi-5 volumio[1394]: info: Executing endpoint metavolumio Feb 17 11:27:25 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 11:27:25 volumiorpi-5 volumio[1394]: info: Executing endpoint metavolumio Feb 17 11:27:25 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: Preload queue cleared Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::ClearQueue Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::stop Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::stPlaybackTimer Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::updateTrackBlock Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrackBlock Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::pushState Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushState Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: MRS: Pushing multiroomSync output Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioGetState Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::serviceStop Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::getTrack 0 Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::serviceStop Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: ControllerMpd::stop Feb 17 11:27:56 volumiorpi-5 volumio[1394]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::clearPlayQueue Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::saveQueue Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CoreStateMachine::addQueueItems Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: CorePlayQueue::addQueueItems Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: Preload queue cleared Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: Adding Item to queue: music-library/NAS/TrueNAS/Joy - Hello Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: Exploding uri music-library/NAS/TrueNAS/Joy - Hello in service mpd Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=Joy/Hello/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FJoy%20-%20Hello%2FJoy%20-%20Hello.cue&metadata=false Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/Joy - Hello/Joy - Hello.cue Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: ALBUMART /albumart?cacheid=938&web=Joy/Hello/extralarge&path=%2Fmnt%2FNAS%2FTrueNAS%2FJoy%20-%20Hello%2FJoy%20-%20Hello.cue&metadata=false Feb 17 11:27:56 volumiorpi-5 volumio[1394]: info: URI /mnt/NAS/TrueNAS/Joy - Hello/Joy - Hello.cue Feb 17 11:27:56 volumiorpi-5 volumio[1394]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:27:56 volumiorpi-5 volumio[1394]: Error: Unable to resolve or reject the same promise twice Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at Socket.emit (node:events:514:28) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at addChunk (node:internal/streams/readable:343:12) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at Readable.push (node:internal/streams/readable:253:10) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 17 11:27:56 volumiorpi-5 volumio[1394]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:27:56 volumiorpi-5 sudo[3270]: volumio : unable to resolve host volumiorpi-5: System error Feb 17 11:27:56 volumiorpi-5 sudo[3270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 11:26' Feb 17 11:27:56 volumiorpi-5 sudo[3270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"