Dec 13 19:56:03 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 13 19:56:04 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:07 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:07 volumio volumio[1244]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::ClearQueue Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::stop Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:07 volumio volumio[1244]: info: CorePlayQueue::clearPlayQueue Dec 13 19:56:07 volumio volumio[1244]: info: CorePlayQueue::saveQueue Dec 13 19:56:07 volumio volumio[1244]: info: CoreCommandRouter::volumioPushQueue Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::addQueueItems Dec 13 19:56:07 volumio volumio[1244]: info: CorePlayQueue::addQueueItems Dec 13 19:56:07 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:07 volumio volumio[1244]: info: Adding Item to queue: upnp/folder/http://192.168.68.52:32469/ContentDirectory/6f74d5cd-368d-6268-7424-7438ea652792/control.xml@f2cfafc25fd0d8d67032 Dec 13 19:56:07 volumio volumio[1244]: info: Exploding uri upnp/folder/http://192.168.68.52:32469/ContentDirectory/6f74d5cd-368d-6268-7424-7438ea652792/control.xml@f2cfafc25fd0d8d67032 in service upnp_browser Dec 13 19:56:07 volumio volumio[1244]: info: CoreCommandRouter::volumioPushQueue Dec 13 19:56:07 volumio volumio[1244]: info: CorePlayQueue::saveQueue Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::updateTrackBlock Dec 13 19:56:07 volumio volumio[1244]: info: CorePlayQueue::getTrackBlock Dec 13 19:56:07 volumio volumio[1244]: info: CoreCommandRouter::volumioPlay Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::play index 0 Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::stop Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::play index undefined Dec 13 19:56:07 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:07 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Dec 13 19:56:13 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 13 19:56:13 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:20 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 13 19:56:21 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:25 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 19:56:25 volumio volumio[1244]: info: CURURI: music-library Dec 13 19:56:25 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 19:56:27 volumio volumio[1244]: info: CURURI: music-library/USB Dec 13 19:56:27 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:28 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 19:56:28 volumio volumio[1244]: info: CURURI: music-library/USB/Elements Dec 13 19:56:28 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:30 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 19:56:30 volumio volumio[1244]: info: CURURI: music-library/USB/Elements/Spear of Destiny Dec 13 19:56:30 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:32 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 19:56:32 volumio volumio[1244]: info: CURURI: music-library/USB/Elements/Spear of Destiny/Outland Dec 13 19:56:32 volumio volumio[1244]: error: Failed LSINFO: null Dec 13 19:56:33 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:35 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 19:56:35 volumio volumio[1244]: info: CURURI: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks] Dec 13 19:56:35 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:35 volumio volumio[1244]: info: Preloading song: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/01 Outlands.mp3 Dec 13 19:56:35 volumio volumio[1244]: info: Preloading song: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/02 Land of Shame.mp3 Dec 13 19:56:35 volumio volumio[1244]: info: Preloading song: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/03 The Traveller.mp3 Dec 13 19:56:35 volumio volumio[1244]: info: Preloading song: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3 Dec 13 19:56:35 volumio volumio[1244]: info: Exploding uri music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/01 Outlands.mp3 in service mpd Dec 13 19:56:35 volumio volumio[1244]: info: ALBUMART /albumart?cacheid=813&web=Spear%20of%20Destiny/Outland%20%5BBonus%20Tracks%5D/extralarge&path=%2Fmnt%2FUSB%2FElements%2FSpear%20of%20Destiny%2FOutland%20%5BBonus%20Tracks%5D%2F01%20Outlands.mp3&metadata=false Dec 13 19:56:35 volumio volumio[1244]: info: URI /mnt/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/01 Outlands.mp3 Dec 13 19:56:35 volumio volumio[1244]: info: Exploding uri music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/02 Land of Shame.mp3 in service mpd Dec 13 19:56:35 volumio volumio[1244]: info: ALBUMART /albumart?cacheid=813&web=Spear%20of%20Destiny/Outland%20%5BBonus%20Tracks%5D/extralarge&path=%2Fmnt%2FUSB%2FElements%2FSpear%20of%20Destiny%2FOutland%20%5BBonus%20Tracks%5D%2F02%20Land%20of%20Shame.mp3&metadata=false Dec 13 19:56:35 volumio volumio[1244]: info: URI /mnt/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/02 Land of Shame.mp3 Dec 13 19:56:35 volumio volumio[1244]: info: Exploding uri music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/03 The Traveller.mp3 in service mpd Dec 13 19:56:35 volumio volumio[1244]: info: ALBUMART /albumart?cacheid=813&web=Spear%20of%20Destiny/Outland%20%5BBonus%20Tracks%5D/extralarge&path=%2Fmnt%2FUSB%2FElements%2FSpear%20of%20Destiny%2FOutland%20%5BBonus%20Tracks%5D%2F03%20The%20Traveller.mp3&metadata=false Dec 13 19:56:35 volumio volumio[1244]: info: URI /mnt/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/03 The Traveller.mp3 Dec 13 19:56:36 volumio volumio[1244]: info: Exploding uri music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3 in service mpd Dec 13 19:56:36 volumio volumio[1244]: info: ALBUMART /albumart?cacheid=813&web=Spear%20of%20Destiny/Outland%20%5BBonus%20Tracks%5D/extralarge&path=%2Fmnt%2FUSB%2FElements%2FSpear%20of%20Destiny%2FOutland%20%5BBonus%20Tracks%5D%2F04%20Was%20That%20You_.mp3&metadata=false Dec 13 19:56:36 volumio volumio[1244]: info: URI /mnt/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::ClearQueue Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::stop Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::clearPlayQueue Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::saveQueue Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushQueue Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::addQueueItems Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::addQueueItems Dec 13 19:56:40 volumio volumio[1244]: info: Preload queue cleared Dec 13 19:56:40 volumio volumio[1244]: info: Adding Item to queue: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/01 Outlands.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Using cached record of: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/01 Outlands.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Adding Item to queue: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/02 Land of Shame.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Using cached record of: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/02 Land of Shame.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Adding Item to queue: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/03 The Traveller.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Using cached record of: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/03 The Traveller.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Adding Item to queue: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: Using cached record of: music-library/USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushQueue Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::saveQueue Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::updateTrackBlock Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrackBlock Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPlay Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::play index 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::stop Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::play index undefined Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::startPlaybackTimer Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3 Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand stop took 46 milliseconds Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces system playlist update Dec 13 19:56:40 volumio volumio[1244]: info: Ignoring MPD Status Update Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand clear took 3 milliseconds Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3" Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces system playlist update Dec 13 19:56:40 volumio volumio[1244]: info: Ignoring MPD Status Update Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces system playlist update Dec 13 19:56:40 volumio volumio[1244]: info: Ignoring MPD Status Update Dec 13 19:56:40 volumio volumio[1244]: error: updateQueue error: null Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces system playlist update Dec 13 19:56:40 volumio volumio[1244]: info: Ignoring MPD Status Update Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 38ms Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand add "USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3" took 38 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 37ms Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 36ms Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand play Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces system playlist update Dec 13 19:56:40 volumio volumio[1244]: info: Ignoring MPD Status Update Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces system playlist update Dec 13 19:56:40 volumio volumio[1244]: info: Ignoring MPD Status Update Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 41ms Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand play took 40 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 43ms Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 28ms Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces state update: player Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::getState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces state update: player Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::getState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces state update: player Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::getState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces state update: player Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::getState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand status took 8 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand status took 7 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand status took 6 milliseconds Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces state update: player Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::getState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Dec 13 19:56:40 volumio volumio[1244]: info: Dec 13 19:56:40 volumio volumio[1244]: ---------------------------- MPD announces state update: player Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::getState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand status Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand status took 80 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Was That You?","artist":"Spear of Destiny","album":"Outland [Bonus Tracks]","uri":"USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3","trackType":"mp3"} Dec 13 19:56:40 volumio volumio[1244]: verbose: CURRENT POSITION 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState stateService play Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState currentStatus stop Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Was That You?","artist":"Spear of Destiny","album":"Outland [Bonus Tracks]","uri":"USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3","trackType":"mp3"} Dec 13 19:56:40 volumio volumio[1244]: verbose: CURRENT POSITION 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState stateService play Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState currentStatus play Dec 13 19:56:40 volumio volumio[1244]: info: Received an update from plugin. extracting info from payload Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Was That You?","artist":"Spear of Destiny","album":"Outland [Bonus Tracks]","uri":"USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3","trackType":"mp3"} Dec 13 19:56:40 volumio volumio[1244]: verbose: CURRENT POSITION 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState stateService play Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState currentStatus play Dec 13 19:56:40 volumio volumio[1244]: info: Received an update from plugin. extracting info from payload Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 307ms Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 480ms Dec 13 19:56:40 volumio volumio[1244]: info: ------------------------------ 479ms Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand status took 398 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand status took 406 milliseconds Dec 13 19:56:40 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 404 milliseconds Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseState Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 19:56:40 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Dec 13 19:56:40 volumio volumio[1244]: info: ControllerMpd::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Was That You?","artist":"Spear of Destiny","album":"Outland [Bonus Tracks]","uri":"USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3","trackType":"mp3"} Dec 13 19:56:40 volumio volumio[1244]: verbose: CURRENT POSITION 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState stateService play Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::syncState currentStatus play Dec 13 19:56:40 volumio volumio[1244]: info: Received an update from plugin. extracting info from payload Dec 13 19:56:40 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:40 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:40 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:41 volumio volumio[1244]: info: ------------------------------ 633ms Dec 13 19:56:41 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 418 milliseconds Dec 13 19:56:41 volumio volumio[1244]: info: sendMpdCommand playlistinfo took 432 milliseconds Dec 13 19:56:41 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Dec 13 19:56:41 volumio volumio[1244]: verbose: ControllerMpd::parseTrackInfo Dec 13 19:56:41 volumio volumio[1244]: info: ControllerMpd::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Was That You?","artist":"Spear of Destiny","album":"Outland [Bonus Tracks]","uri":"USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3","trackType":"mp3"} Dec 13 19:56:41 volumio volumio[1244]: verbose: CURRENT POSITION 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::syncState stateService play Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::syncState currentStatus play Dec 13 19:56:41 volumio volumio[1244]: info: Received an update from plugin. extracting info from payload Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:41 volumio volumio[1244]: info: ControllerMpd::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::servicePushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Was That You?","artist":"Spear of Destiny","album":"Outland [Bonus Tracks]","uri":"USB/Elements/Spear of Destiny/Outland [Bonus Tracks]/04 Was That You_.mp3","trackType":"mp3"} Dec 13 19:56:41 volumio volumio[1244]: verbose: CURRENT POSITION 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::syncState stateService play Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::syncState currentStatus play Dec 13 19:56:41 volumio volumio[1244]: info: Received an update from plugin. extracting info from payload Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:41 volumio volumio[1244]: info: CoreStateMachine::pushState Dec 13 19:56:41 volumio volumio[1244]: info: CorePlayQueue::getTrack 3 Dec 13 19:56:41 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Dec 13 19:56:41 volumio volumio[1244]: info: ------------------------------ 1174ms Dec 13 19:56:41 volumio volumio[1244]: info: ------------------------------ 1174ms Dec 13 19:56:51 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 19:56:51 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 13 19:56:59 volumio sudo[2339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 13 19:56:59 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 13 19:56:59 volumio sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 13 19:56:59 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:56:59 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:56:59 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:56:59 volumio sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 13 19:56:59 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 19:56:59 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root Dec 13 19:56:59 volumio volumio[1244]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 13 19:56:59 volumio volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 19:56:59 volumio volumio[1244]: TypeError: Cannot read properties of undefined (reading 'split') Dec 13 19:56:59 volumio volumio[1244]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 13 19:56:59 volumio volumio[1244]: at ChildProcess.exithandler (node:child_process:413:7) Dec 13 19:56:59 volumio volumio[1244]: at ChildProcess.emit (node:events:514:28) Dec 13 19:56:59 volumio volumio[1244]: at maybeClose (node:internal/child_process:1105:16) Dec 13 19:56:59 volumio volumio[1244]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 13 19:56:59 volumio volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 19:56:59 volumio systemd[1]: Reloading. Dec 13 19:57:00 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 19:56' Dec 13 19:57:00 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"