-- Logs begin at Tue 2024-08-27 06:00:15 CEST, end at Wed 2024-08-28 20:53:11 CEST. -- Aug 28 20:52:03 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:07 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:11 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:15 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:19 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:23 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:27 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:31 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:35 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:39 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:43 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:47 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:51 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:55 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:56 volumio-doma volumio[1088]: info: Preload queue cleared Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::ClearQueue Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::stPlaybackTimer Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::updateTrackBlock Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrackBlock Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 1 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::serviceStop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 1 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::serviceStop Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::stop Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::clearPlayQueue Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::saveQueue Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::addQueueItems Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::addQueueItems Aug 28 20:52:56 volumio-doma volumio[1088]: info: Preload queue cleared Aug 28 20:52:56 volumio-doma volumio[1088]: info: Adding Item to queue: music-library/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128] Aug 28 20:52:56 volumio-doma volumio[1088]: info: Exploding uri music-library/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128] in service mpd Aug 28 20:52:56 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:52:56 volumio-doma volumio[1088]: info: Aug 28 20:52:56 volumio-doma volumio[1088]: ---------------------------- MPD announces state update: player Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F01-Hold%20On.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/01-Hold On.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F02-Don't%20You.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/02-Don't You.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F03-Strive.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/03-Strive.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F04-A%20Kiss%20to%20Build%20a%20Dream%20On.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/04-A Kiss to Build a Dream On.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F05-Down%20Home.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/05-Down Home.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F06-Novocaine.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/06-Novocaine.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F07-Darkest%20Dark%2C%20Brightest%20Brigh.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/07-Darkest Dark, Brightest Brigh.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F08-Tundra.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/08-Tundra.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F09-Good%20Mystery.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/09-Good Mystery.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F10-Washing%20Day.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/10-Washing Day.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F11-Sneak.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/11-Sneak.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F12-Full%20Moon%20in%20Paris.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/12-Full Moon in Paris.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F13-Just%20Like%20a%20Woman.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/13-Just Like a Woman.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=Amber%20Rubarth/Sessions%20from%20the%2017th%20Ward/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FAmber%20Rubarth%20-%20Sessions%20from%20the%2017th%20Ward%20(2012)%20%5BHDtracks%20DSD128%5D%2F14-Storms%20Are%20On%20the%20Ocean.dsf&metadata=false Aug 28 20:52:56 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/14-Storms Are On the Ocean.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand stop took 134 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::getState Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::saveQueue Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::updateTrackBlock Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrackBlock Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPlay Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::play index 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::play index undefined Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::startPlaybackTimer Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::clearAddPlayTracks NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/01-Hold On.dsf Aug 28 20:52:56 volumio-doma volumio[1088]: info: Setting Volume to 100 automatically for DSD Aug 28 20:52:56 volumio-doma volumio[1088]: info: VolumeController::SetAlsaVolume100 Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand status took 46 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand stop took 13 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::parseState Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 20:52:56 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:52:56 volumio-doma volumio[1088]: info: Aug 28 20:52:56 volumio-doma volumio[1088]: ---------------------------- MPD announces system playlist update Aug 28 20:52:56 volumio-doma volumio[1088]: info: Ignoring MPD Status Update Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand clear took 12 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/01-Hold On.dsf" Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::servicePushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: 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":"02 - Vltava (Allegro comodo, non agitato).wav","artist":null,"album":null,"uri":"NAS/zoo/Bedřich Smetana Má vlast/02 - Vltava (Allegro comodo, non agitato).wav","trackType":"wav"} Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: CURRENT POSITION 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::syncState stateService stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: No code Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: ------------------------------ 80ms Aug 28 20:52:56 volumio-doma volumio[1088]: error: updateQueue error: null Aug 28 20:52:56 volumio-doma volumio[1088]: info: Aug 28 20:52:56 volumio-doma volumio[1088]: ---------------------------- MPD announces system playlist update Aug 28 20:52:56 volumio-doma volumio[1088]: info: Ignoring MPD Status Update Aug 28 20:52:56 volumio-doma volumio[1088]: info: ------------------------------ 17ms Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand add "NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/01-Hold On.dsf" took 16 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Aug 28 20:52:56 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:52:56 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:52:56 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:52:56 volumio-doma volumio[1088]: info: ------------------------------ 18ms Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand play took 17 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: info: Aug 28 20:52:56 volumio-doma volumio[1088]: ---------------------------- MPD announces state update: player Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::getState Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:52:56 volumio-doma volumio[1088]: info: Aug 28 20:52:56 volumio-doma volumio[1088]: ---------------------------- MPD announces state update: player Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::getState Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand status took 10 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::parseState Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand status took 56 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::parseState Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::servicePushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":341,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hold On","artist":"Amber Rubarth","album":"Sessions from the 17th Ward","uri":"NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/01-Hold On.dsf","trackType":"dsf"} Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: CURRENT POSITION 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::syncState stateService play Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Aug 28 20:52:56 volumio-doma volumio[1088]: info: ------------------------------ 79ms Aug 28 20:52:56 volumio-doma volumio[1088]: info: sendMpdCommand playlistinfo took 33 milliseconds Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:52:56 volumio-doma volumio[1088]: info: ControllerMpd::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::servicePushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":341,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hold On","artist":"Amber Rubarth","album":"Sessions from the 17th Ward","uri":"NAS/zoo/Amber Rubarth - Sessions from the 17th Ward (2012) [HDtracks DSD128]/01-Hold On.dsf","trackType":"dsf"} Aug 28 20:52:56 volumio-doma volumio[1088]: verbose: CURRENT POSITION 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::syncState stateService play Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::syncState currentStatus play Aug 28 20:52:56 volumio-doma volumio[1088]: info: Received an update from plugin. extracting info from payload Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:52:56 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:52:56 volumio-doma volumio[1088]: info: ------------------------------ 146ms Aug 28 20:52:56 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:52:59 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:52:59 volumio-doma volumio[1088]: info: Executing endpoint metavolumio Aug 28 20:52:59 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 28 20:52:59 volumio-doma volumio[1088]: info: Executing endpoint metavolumio Aug 28 20:52:59 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 28 20:52:59 volumio-doma volumio[1088]: info: Executing endpoint metavolumio Aug 28 20:52:59 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 28 20:53:03 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:53:07 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 20:53:11 volumio-doma volumio[1088]: info: Preload queue cleared Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::ClearQueue Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::stop Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::stPlaybackTimer Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::updateTrackBlock Aug 28 20:53:11 volumio-doma volumio[1088]: info: CorePlayQueue::getTrackBlock Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::pushState Aug 28 20:53:11 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushState Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::serviceStop Aug 28 20:53:11 volumio-doma volumio[1088]: info: CorePlayQueue::getTrack 0 Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreCommandRouter::serviceStop Aug 28 20:53:11 volumio-doma volumio[1088]: info: ControllerMpd::stop Aug 28 20:53:11 volumio-doma volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 20:53:11 volumio-doma volumio[1088]: info: CorePlayQueue::clearPlayQueue Aug 28 20:53:11 volumio-doma volumio[1088]: info: CorePlayQueue::saveQueue Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:53:11 volumio-doma volumio[1088]: info: CoreStateMachine::addQueueItems Aug 28 20:53:11 volumio-doma volumio[1088]: info: CorePlayQueue::addQueueItems Aug 28 20:53:11 volumio-doma volumio[1088]: info: Preload queue cleared Aug 28 20:53:11 volumio-doma volumio[1088]: info: Adding Item to queue: music-library/NAS/zoo/Belinda Carlisle [Vinyl] Aug 28 20:53:11 volumio-doma volumio[1088]: info: Exploding uri music-library/NAS/zoo/Belinda Carlisle [Vinyl] in service mpd Aug 28 20:53:11 volumio-doma volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 28 20:53:11 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=/Belinda/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FBelinda%20Carlisle%20%5BVinyl%5D%2FBelinda%2FBelinda.cue&metadata=false Aug 28 20:53:11 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Belinda Carlisle [Vinyl]/Belinda/Belinda.cue Aug 28 20:53:11 volumio-doma volumio[1088]: info: ALBUMART /albumart?cacheid=560&web=/Belinda/extralarge&path=%2Fmnt%2FNAS%2Fzoo%2FBelinda%20Carlisle%20%5BVinyl%5D%2FBelinda%2FBelinda.cue&metadata=false Aug 28 20:53:11 volumio-doma volumio[1088]: info: URI /mnt/NAS/zoo/Belinda Carlisle [Vinyl]/Belinda/Belinda.cue Aug 28 20:53:11 volumio-doma volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 20:53:11 volumio-doma volumio[1088]: Error: Unable to resolve or reject the same promise twice Aug 28 20:53:11 volumio-doma volumio[1088]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 28 20:53:11 volumio-doma volumio[1088]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 28 20:53:11 volumio-doma volumio[1088]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 28 20:53:11 volumio-doma volumio[1088]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 28 20:53:11 volumio-doma volumio[1088]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 28 20:53:11 volumio-doma volumio[1088]: at Socket.emit (events.js:400:28) Aug 28 20:53:11 volumio-doma volumio[1088]: at addChunk (internal/streams/readable.js:293:12) Aug 28 20:53:11 volumio-doma volumio[1088]: at readableAddChunk (internal/streams/readable.js:263:11) Aug 28 20:53:11 volumio-doma volumio[1088]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 28 20:53:11 volumio-doma volumio[1088]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 28 20:53:11 volumio-doma volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 20:53:11 volumio-doma sudo[27806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 20:52 Aug 28 20:53:11 volumio-doma sudo[27806]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"