Oct 12 16:55:09 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::ClearQueue Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::stop Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::serviceStop Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::serviceStop Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::stop Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::clearPlayQueue Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::saveQueue Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushQueue Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::addQueueItems Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::addQueueItems Oct 12 16:55:09 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:55:09 volumio volumio[1140]: info: Adding Item to queue: music-library/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025) Oct 12 16:55:09 volumio volumio[1140]: info: Exploding uri music-library/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025) in service mpd Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand stop took 13 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)%2F01.%20Blues%20A%20La%20Carte.dsf&metadata=false Oct 12 16:55:09 volumio volumio[1140]: info: URI /mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf Oct 12 16:55:09 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)%2F02.%20Harry's%20Last%20Stand.dsf&metadata=false Oct 12 16:55:09 volumio volumio[1140]: info: URI /mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf Oct 12 16:55:09 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)%2F03.%20Down%20In%20The%20Depths.dsf&metadata=false Oct 12 16:55:09 volumio volumio[1140]: info: URI /mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/03. Down In The Depths.dsf Oct 12 16:55:09 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)%2F04.%20Pug%20Nose.dsf&metadata=false Oct 12 16:55:09 volumio volumio[1140]: info: URI /mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/04. Pug Nose.dsf Oct 12 16:55:09 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)%2F05.%20Black%20Diamond.dsf&metadata=false Oct 12 16:55:09 volumio volumio[1140]: info: URI /mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/05. Black Diamond.dsf Oct 12 16:55:09 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2Fmnt%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)%2F06.%20Mack%20The%20Knife.dsf&metadata=false Oct 12 16:55:09 volumio volumio[1140]: info: URI /mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/06. Mack The Knife.dsf Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushQueue Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::saveQueue Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPlay Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::play index 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::stop Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::play index undefined Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 2 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand stop took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:09 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand clear took 0 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf" Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: 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":"Любовь","artist":"СерьГа","album":"ЛучшиЕ ПесНи","uri":"NAS/Music/Серьга/2001 - Лучшие песни/01 СерьГа - Любовь.flac","trackType":"flac"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService stop Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus stop Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: No code Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 16:55:09 volumio volumio[1140]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 5ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 4ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 4ms Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:09 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:09 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:09 volumio volumio[1140]: error: updateQueue error: null Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:09 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 4ms Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand add "NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf" took 4 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand play Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:09 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 2ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 2ms Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand play took 2 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:09 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 1ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 0ms Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 125 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 125 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 125 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus stop Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:09 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:09 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 129ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 128ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 128ms Oct 12 16:55:09 volumio volumio[1140]: info: Oct 12 16:55:09 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:09 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":377,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:09 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 5ms Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 4ms Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand status took 5 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:09 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:09 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:09 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":998,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:09 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:09 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:09 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:09 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:09 volumio volumio[1140]: info: ------------------------------ 8ms Oct 12 16:55:17 volumio volumio[1140]: info: CoreCommandRouter::volumioGetState Oct 12 16:55:17 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioSeek Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::seek Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: TRACKBLOCK {"uri":"mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","service":"mpd","name":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)&metadata=false","duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::seek Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: error: null Oct 12 16:55:23 volumio volumio[1140]: info: Oct 12 16:55:23 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:23 volumio volumio[1140]: info: Oct 12 16:55:23 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:23 volumio volumio[1140]: info: Oct 12 16:55:23 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:23 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:23 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:23 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:23 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:23 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:23 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:23 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113000,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:23 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:23 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113000,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:23 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:23 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113000,"duration":342,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:23 volumio volumio[1140]: verbose: CURRENT POSITION 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:23 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:23 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:23 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:23 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:23 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:23 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:25 volumio volumio[1140]: info: CoreCommandRouter::volumioNext Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::next Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::stop Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Oct 12 16:55:25 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:25 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:25 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:25 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:25 volumio volumio[1140]: info: CoreStateMachine::serviceStop Oct 12 16:55:25 volumio volumio[1140]: info: CorePlayQueue::getTrack 0 Oct 12 16:55:25 volumio volumio[1140]: info: CoreCommandRouter::serviceStop Oct 12 16:55:25 volumio volumio[1140]: info: ControllerMpd::stop Oct 12 16:55:25 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand stop took 110 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::play index undefined Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand stop took 1 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand clear Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:26 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:26 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:26 volumio volumio[1140]: error: updateQueue error: null Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand clear took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 0ms Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf" Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: 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":"Blues A La Carte","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/01. Blues A La Carte.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService stop Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus stop Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: No code Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:26 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:26 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:26 volumio volumio[1140]: error: updateQueue error: null Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:26 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 2ms Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand add "NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf" took 2 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 0ms Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand play Oct 12 16:55:26 volumio volumio[1140]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces system playlist update Oct 12 16:55:26 volumio volumio[1140]: info: Ignoring MPD Status Update Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 1ms Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 1ms Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand play took 2 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 1ms Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 105 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 106 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 106 milliseconds Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: info: Oct 12 16:55:26 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus stop Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":497,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:26 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":998,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:26 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 108ms Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 109ms Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 109ms Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand status took 3 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":998,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:26 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":998,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:26 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 6ms Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 7ms Oct 12 16:55:26 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 12 16:55:26 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:26 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":998,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:26 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:26 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:26 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:26 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:26 volumio volumio[1140]: info: ------------------------------ 11ms Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioSeek Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::seek Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: TRACKBLOCK {"uri":"mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","service":"mpd","name":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)&metadata=false","duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::seek Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: error: null Oct 12 16:55:27 volumio volumio[1140]: info: Oct 12 16:55:27 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:27 volumio volumio[1140]: info: Oct 12 16:55:27 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:27 volumio volumio[1140]: info: Oct 12 16:55:27 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:27 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:27 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:27 volumio volumio[1140]: info: sendMpdCommand status took 1 milliseconds Oct 12 16:55:27 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:27 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51000,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:27 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:27 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51000,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:27 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:27 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:27 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:27 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 12 16:55:27 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:27 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51000,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:27 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:27 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:27 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:27 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:27 volumio volumio[1140]: info: ------------------------------ 6ms Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioSeek Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::seek Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: TRACKBLOCK {"uri":"mnt/NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","service":"mpd","name":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=894&web=Wayne%20Shorter/Introducing%20Wayne%20Shorter/extralarge&path=%2FNAS%2FMusic%2FWayne%20Shorter%20'Introducing%20Wayne%20Shorter'%20(1960%2C%202025)&metadata=false","duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::startPlaybackTimer Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::seek Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: error: null Oct 12 16:55:29 volumio volumio[1140]: info: Oct 12 16:55:29 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:29 volumio volumio[1140]: info: Oct 12 16:55:29 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:29 volumio volumio[1140]: info: Oct 12 16:55:29 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:29 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:29 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:29 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:29 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:29 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:29 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:29 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101000,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:29 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:29 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101000,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:29 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:29 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101000,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:29 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::syncState stateService play Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus play Oct 12 16:55:29 volumio volumio[1140]: info: Received an update from plugin. extracting info from payload Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:29 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:29 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:29 volumio volumio[1140]: info: ------------------------------ 4ms Oct 12 16:55:29 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:29 volumio volumio[1140]: info: ------------------------------ 3ms Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::volumioPause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::servicePause Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::servicePause Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::pause Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand pause Oct 12 16:55:31 volumio volumio[1140]: info: Oct 12 16:55:31 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand pause took 1 milliseconds Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:31 volumio volumio[1140]: info: Oct 12 16:55:31 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:31 volumio volumio[1140]: info: Oct 12 16:55:31 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand status took 0 milliseconds Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::parseState Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:31 volumio volumio[1140]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:31 volumio volumio[1140]: verbose: ControllerMpd::parseTrackInfo Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":103372,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:31 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::syncState stateService pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":103372,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:31 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::syncState stateService pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:55:31 volumio volumio[1140]: info: ControllerMpd::pushState Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::servicePushState Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":103372,"duration":303,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Harry's Last Stand","artist":"Wayne Shorter","album":"Introducing Wayne Shorter","uri":"NAS/Music/Wayne Shorter 'Introducing Wayne Shorter' (1960, 2025)/02. Harry's Last Stand.dsf","trackType":"dsf"} Oct 12 16:55:31 volumio volumio[1140]: verbose: CURRENT POSITION 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::syncState stateService pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::syncState currentStatus pause Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:55:31 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:55:31 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:55:31 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:55:31 volumio volumio[1140]: info: ------------------------------ 2ms Oct 12 16:55:31 volumio volumio[1140]: info: ------------------------------ 2ms Oct 12 16:55:31 volumio volumio[1140]: info: ------------------------------ 2ms Oct 12 16:55:40 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 16:55:40 volumio volumio[1140]: info: CURURI: music-library/USB Oct 12 16:55:40 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:55:45 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 16:55:45 volumio volumio[1140]: info: CURURI: music-library/USB/Music_A-L Oct 12 16:55:45 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:55:46 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 16:55:46 volumio volumio[1140]: info: CURURI: music-library/USB/Music_A-L/Music Oct 12 16:55:46 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:56:06 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 16:56:06 volumio volumio[1140]: info: CURURI: music-library/USB/Music_A-L/Music/Al Di Meola Oct 12 16:56:07 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:56:19 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:56:19 volumio volumio[1140]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::ClearQueue Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::stop Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::updateTrackBlock Oct 12 16:56:19 volumio volumio[1140]: info: CorePlayQueue::getTrackBlock Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::stPlaybackTimer Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::pushState Oct 12 16:56:19 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:56:19 volumio volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 16:56:19 volumio volumio[1140]: info: CoreCommandRouter::volumioPushState Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::serviceStop Oct 12 16:56:19 volumio volumio[1140]: info: CorePlayQueue::getTrack 1 Oct 12 16:56:19 volumio volumio[1140]: info: CoreCommandRouter::serviceStop Oct 12 16:56:19 volumio volumio[1140]: info: ControllerMpd::stop Oct 12 16:56:19 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 16:56:19 volumio volumio[1140]: info: CorePlayQueue::clearPlayQueue Oct 12 16:56:19 volumio volumio[1140]: info: CorePlayQueue::saveQueue Oct 12 16:56:19 volumio volumio[1140]: info: CoreCommandRouter::volumioPushQueue Oct 12 16:56:19 volumio volumio[1140]: info: CoreStateMachine::addQueueItems Oct 12 16:56:19 volumio volumio[1140]: info: CorePlayQueue::addQueueItems Oct 12 16:56:19 volumio volumio[1140]: info: Preload queue cleared Oct 12 16:56:19 volumio volumio[1140]: info: Adding Item to queue: music-library/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar Oct 12 16:56:19 volumio volumio[1140]: info: Exploding uri music-library/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar in service mpd Oct 12 16:56:19 volumio volumio[1140]: info: Oct 12 16:56:19 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:56:19 volumio volumio[1140]: info: sendMpdCommand stop took 3 milliseconds Oct 12 16:56:19 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:56:19 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:56:19 volumio volumio[1140]: info: Oct 12 16:56:19 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:56:19 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:56:19 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:56:19 volumio volumio[1140]: info: Oct 12 16:56:19 volumio volumio[1140]: ---------------------------- MPD announces state update: player Oct 12 16:56:19 volumio volumio[1140]: info: ControllerMpd::getState Oct 12 16:56:19 volumio volumio[1140]: verbose: ControllerMpd::sendMpdCommand status Oct 12 16:56:19 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Laurindo%20Almeida/Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_A-L%2FMusic%2FAl%20Di%20Meola%2F1977%20-%20Virtuoso%20Guitar%2F01.%20Yesterday.dsf&metadata=false Oct 12 16:56:19 volumio volumio[1140]: info: URI /mnt/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar/01. Yesterday.dsf Oct 12 16:56:19 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Laurindo%20Almeida/Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_A-L%2FMusic%2FAl%20Di%20Meola%2F1977%20-%20Virtuoso%20Guitar%2F02.%20Jazz-Tuno%20At%20The%20Mission.dsf&metadata=false Oct 12 16:56:19 volumio volumio[1140]: info: URI /mnt/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar/02. Jazz-Tuno At The Mission.dsf Oct 12 16:56:19 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Laurindo%20Almeida/Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_A-L%2FMusic%2FAl%20Di%20Meola%2F1977%20-%20Virtuoso%20Guitar%2F03.%20Late%20Last%20Night.dsf&metadata=false Oct 12 16:56:19 volumio volumio[1140]: info: URI /mnt/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar/03. Late Last Night.dsf Oct 12 16:56:19 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Laurindo%20Almeida/Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_A-L%2FMusic%2FAl%20Di%20Meola%2F1977%20-%20Virtuoso%20Guitar%2F04.%20Sonata%20For%20Guitar%20And%20Cello%20In%20Three%20Movements%20(Allegretto%20Comodo%2C%20Adagio%2C%20C....dsf&metadata=false Oct 12 16:56:19 volumio volumio[1140]: info: URI /mnt/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar/04. Sonata For Guitar And Cello In Three Movements (Allegretto Comodo, Adagio, C....dsf Oct 12 16:56:19 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Laurindo%20Almeida/Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_A-L%2FMusic%2FAl%20Di%20Meola%2F1977%20-%20Virtuoso%20Guitar%2FLaurindo%20Almeida%20-%20Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D.cue&metadata=false Oct 12 16:56:19 volumio volumio[1140]: info: URI /mnt/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar/Laurindo Almeida - Virtuoso Guitar (1977) [Direct To Disc 45RPM].cue Oct 12 16:56:19 volumio volumio[1140]: info: ALBUMART /albumart?cacheid=894&web=Laurindo%20Almeida/Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_A-L%2FMusic%2FAl%20Di%20Meola%2F1977%20-%20Virtuoso%20Guitar%2FLaurindo%20Almeida%20-%20Virtuoso%20Guitar%20(1977)%20%5BDirect%20To%20Disc%2045RPM%5D.cue&metadata=false Oct 12 16:56:19 volumio volumio[1140]: info: URI /mnt/USB/Music_A-L/Music/Al Di Meola/1977 - Virtuoso Guitar/Laurindo Almeida - Virtuoso Guitar (1977) [Direct To Disc 45RPM].cue Oct 12 16:56:19 volumio volumio[1140]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 16:56:19 volumio volumio[1140]: Error: Unable to resolve or reject the same promise twice Oct 12 16:56:19 volumio volumio[1140]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 12 16:56:19 volumio volumio[1140]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 12 16:56:19 volumio volumio[1140]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 12 16:56:19 volumio volumio[1140]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 12 16:56:19 volumio volumio[1140]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 12 16:56:19 volumio volumio[1140]: at Socket.emit (node:events:514:28) Oct 12 16:56:19 volumio volumio[1140]: at addChunk (node:internal/streams/readable:343:12) Oct 12 16:56:19 volumio volumio[1140]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 12 16:56:19 volumio volumio[1140]: at Readable.push (node:internal/streams/readable:253:10) Oct 12 16:56:19 volumio volumio[1140]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 12 16:56:19 volumio volumio[1140]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 16:56:19 volumio sudo[15983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 16:55' Oct 12 16:56:19 volumio sudo[15983]: 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="f107261a9bd5157ff5c5bbfacc2d3aef88ae641e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="7f1bd9e83b67dc80eb7e0d85089b6e1994df1be5" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Apr 21 10:46:40 UTC 2026" VOLUMIO_VERSION="4.143" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="60cbb92254e29470ae425bda0da9f4c8"