-- Logs begin at Fri 2025-12-26 04:57:16 EST, end at Sat 2025-12-27 09:29:22 EST. -- Dec 27 09:28:07 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 09:28:07 volumiogarage volumio[774]: info: CURURI: artists:// Dec 27 09:28:07 volumiogarage volumio[774]: info: Preload queue cleared Dec 27 09:28:11 volumiogarage volumio[774]: Searching plugin music_service/mpd Dec 27 09:28:11 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 09:28:12 volumiogarage volumio[774]: info: All search sources collected, pushing search results Dec 27 09:28:14 volumiogarage volumio[774]: Searching plugin music_service/mpd Dec 27 09:28:14 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 09:28:15 volumiogarage volumio[774]: info: All search sources collected, pushing search results Dec 27 09:28:15 volumiogarage volumio[774]: Searching plugin music_service/mpd Dec 27 09:28:15 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 27 09:28:16 volumiogarage volumio[774]: info: All search sources collected, pushing search results Dec 27 09:28:17 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 09:28:17 volumiogarage volumio[774]: info: CURURI: artists://Mark%20Knopfler Dec 27 09:28:17 volumiogarage volumio[774]: info: Preload queue cleared Dec 27 09:28:17 volumiogarage volumio[774]: info: Preload queue cleared Dec 27 09:28:20 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 09:28:20 volumiogarage volumio[774]: info: CURURI: artists://Mark%20Knopfler/Golden%20Heart Dec 27 09:28:20 volumiogarage volumio[774]: info: Preload queue cleared Dec 27 09:28:22 volumiogarage volumio[774]: info: Preload queue cleared Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::ClearQueue Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::stPlaybackTimer Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::updateTrackBlock Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrackBlock Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::serviceStop Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::serviceStop Dec 27 09:28:22 volumiogarage volumio[774]: info: ControllerMpd::stop Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::clearPlayQueue Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::saveQueue Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushQueue Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::addQueueItems Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::addQueueItems Dec 27 09:28:22 volumiogarage volumio[774]: info: Preload queue cleared Dec 27 09:28:22 volumiogarage volumio[774]: info: Adding Item to queue: artists://Mark%20Knopfler/Golden%20Heart Dec 27 09:28:22 volumiogarage volumio[774]: info: Exploding uri artists://Mark%20Knopfler/Golden%20Heart in service mpd Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushQueue Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::saveQueue Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::updateTrackBlock Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrackBlock Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPlay Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::play index 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::play index undefined Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::startPlaybackTimer Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::clearAddPlayTracks NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand stop took 31 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand stop took 3 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:22 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:22 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces system playlist update Dec 27 09:28:22 volumiogarage volumio[774]: info: Ignoring MPD Status Update Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand status took 5 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand clear took 5 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand status took 3 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand status took 4 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand add "NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a" Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:22 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 27 09:28:22 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: No code Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 27 09:28:22 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus stop Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: No code Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:22 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:22 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:22 volumiogarage volumio[774]: info: ------------------------------ 23ms Dec 27 09:28:22 volumiogarage volumio[774]: info: ------------------------------ 23ms Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces system playlist update Dec 27 09:28:22 volumiogarage volumio[774]: info: Ignoring MPD Status Update Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces system playlist update Dec 27 09:28:22 volumiogarage volumio[774]: info: Ignoring MPD Status Update Dec 27 09:28:22 volumiogarage volumio[774]: error: updateQueue error: null Dec 27 09:28:22 volumiogarage volumio[774]: info: Dec 27 09:28:22 volumiogarage volumio[774]: ---------------------------- MPD announces system playlist update Dec 27 09:28:22 volumiogarage volumio[774]: info: Ignoring MPD Status Update Dec 27 09:28:22 volumiogarage volumio[774]: info: ------------------------------ 26ms Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: info: sendMpdCommand add "NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a" took 23 milliseconds Dec 27 09:28:22 volumiogarage volumio[774]: info: ------------------------------ 5ms Dec 27 09:28:22 volumiogarage volumio[774]: info: ------------------------------ 4ms Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:28:22 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand play Dec 27 09:28:22 volumiogarage volumio[774]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 27 09:28:22 volumiogarage volumio[774]: info: ------------------------------ 34ms Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces system playlist update Dec 27 09:28:23 volumiogarage volumio[774]: info: Ignoring MPD Status Update Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces system playlist update Dec 27 09:28:23 volumiogarage volumio[774]: info: Ignoring MPD Status Update Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 10ms Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand play took 7 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 7ms Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 7ms Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand status took 8 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand status took 6 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand status took 5 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand status took 4 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"273 Kbps","isStreaming":false,"title":"Darling Pretty","artist":"Mark Knopfler","album":"Golden Heart","uri":"NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a","trackType":"m4a"} Dec 27 09:28:23 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService play Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus stop Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"273 Kbps","isStreaming":false,"title":"Darling Pretty","artist":"Mark Knopfler","album":"Golden Heart","uri":"NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a","trackType":"m4a"} Dec 27 09:28:23 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService play Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus play Dec 27 09:28:23 volumiogarage volumio[774]: info: Received an update from plugin. extracting info from payload Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"273 Kbps","isStreaming":false,"title":"Darling Pretty","artist":"Mark Knopfler","album":"Golden Heart","uri":"NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a","trackType":"m4a"} Dec 27 09:28:23 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService play Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus play Dec 27 09:28:23 volumiogarage volumio[774]: info: Received an update from plugin. extracting info from payload Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 28ms Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 32ms Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 31ms Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:23 volumiogarage volumio[774]: info: Dec 27 09:28:23 volumiogarage volumio[774]: ---------------------------- MPD announces state update: player Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::getState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"play","position":0,"seek":686,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"273 Kbps","isStreaming":false,"title":"Darling Pretty","artist":"Mark Knopfler","album":"Golden Heart","uri":"NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a","trackType":"m4a"} Dec 27 09:28:23 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService play Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus play Dec 27 09:28:23 volumiogarage volumio[774]: info: Received an update from plugin. extracting info from payload Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 41ms Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand status took 27 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand status took 26 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseState Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 09:28:23 volumiogarage volumio[774]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"273 Kbps","isStreaming":false,"title":"Darling Pretty","artist":"Mark Knopfler","album":"Golden Heart","uri":"NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a","trackType":"m4a"} Dec 27 09:28:23 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService play Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus play Dec 27 09:28:23 volumiogarage volumio[774]: info: Received an update from plugin. extracting info from payload Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: ControllerMpd::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::servicePushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"273 Kbps","isStreaming":false,"title":"Darling Pretty","artist":"Mark Knopfler","album":"Golden Heart","uri":"NAS/Clemusic/Mark Knopfler/Golden Heart/01 Darling Pretty.m4a","trackType":"m4a"} Dec 27 09:28:23 volumiogarage volumio[774]: verbose: CURRENT POSITION 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState stateService play Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::syncState currentStatus play Dec 27 09:28:23 volumiogarage volumio[774]: info: Received an update from plugin. extracting info from payload Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreStateMachine::pushState Dec 27 09:28:23 volumiogarage volumio[774]: info: CorePlayQueue::getTrack 0 Dec 27 09:28:23 volumiogarage volumio[774]: info: CoreCommandRouter::volumioPushState Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 45ms Dec 27 09:28:23 volumiogarage volumio[774]: info: ------------------------------ 44ms Dec 27 09:29:21 volumiogarage volumio[774]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 09:29:21 volumiogarage volumio[774]: TypeError: Cannot read property 'slice' of null Dec 27 09:29:21 volumiogarage volumio[774]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 27 09:29:21 volumiogarage volumio[774]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 27 09:29:21 volumiogarage volumio[774]: at Object.onceWrapper (events.js:422:26) Dec 27 09:29:21 volumiogarage volumio[774]: at ClientRequest.emit (events.js:315:20) Dec 27 09:29:21 volumiogarage volumio[774]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 27 09:29:21 volumiogarage volumio[774]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 27 09:29:21 volumiogarage volumio[774]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 27 09:29:21 volumiogarage volumio[774]: at TLSSocket.emit (events.js:315:20) Dec 27 09:29:21 volumiogarage volumio[774]: at addChunk (internal/streams/readable.js:309:12) Dec 27 09:29:21 volumiogarage volumio[774]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 27 09:29:21 volumiogarage volumio[774]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 27 09:29:21 volumiogarage volumio[774]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 27 09:29:21 volumiogarage volumio[774]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 09:29:22 volumiogarage sudo[6269]: volumio : unable to resolve host volumiogarage Dec 27 09:29:22 volumiogarage sudo[6269]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 27 09:29:22 volumiogarage sudo[6269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 09:28 Dec 27 09:29:22 volumiogarage sudo[6269]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"