-- Logs begin at Tue 2024-04-02 15:42:42 CEST, end at Mon 2024-04-15 19:46:16 CEST. -- Apr 15 19:45:00 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:04 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:08 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:13 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:18 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:22 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand rescan Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: Apr 15 19:45:23 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand rescan took 3 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: Apr 15 19:45:23 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: Apr 15 19:45:23 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: Apr 15 19:45:23 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 11 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 10 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 8 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 6 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 6 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 5 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:23 minidsp-shd volumio[714]: 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} Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: No code Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:23 minidsp-shd volumio[714]: 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} Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: No code Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:23 minidsp-shd volumio[714]: 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} Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: No code Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ------------------------------ 202ms Apr 15 19:45:23 minidsp-shd volumio[714]: info: ------------------------------ 199ms Apr 15 19:45:23 minidsp-shd volumio[714]: info: ------------------------------ 198ms Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: Apr 15 19:45:23 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: info: Apr 15 19:45:23 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 256 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 255 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 59 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 57 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 57 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: info: sendMpdCommand status took 55 milliseconds Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:23 minidsp-shd volumio[714]: 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} Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: No code Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:23 minidsp-shd volumio[714]: 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} Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: No code Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:23 minidsp-shd volumio[714]: 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} Apr 15 19:45:23 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: No code Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:23 minidsp-shd volumio[714]: info: ------------------------------ 421ms Apr 15 19:45:23 minidsp-shd volumio[714]: info: ------------------------------ 224ms Apr 15 19:45:23 minidsp-shd volumio[714]: info: ------------------------------ 222ms Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:23 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand update Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: Apr 15 19:45:25 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand update took 6 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: Apr 15 19:45:25 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: Apr 15 19:45:25 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: Apr 15 19:45:25 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 10 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 8 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 8 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 7 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 6 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 4 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:25 minidsp-shd volumio[714]: 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} Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: No code Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:25 minidsp-shd volumio[714]: 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} Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: No code Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:25 minidsp-shd volumio[714]: 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} Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: No code Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ------------------------------ 173ms Apr 15 19:45:25 minidsp-shd volumio[714]: info: ------------------------------ 171ms Apr 15 19:45:25 minidsp-shd volumio[714]: info: ------------------------------ 170ms Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: Apr 15 19:45:25 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: Apr 15 19:45:25 minidsp-shd volumio[714]: ---------------------------- MPD announces state update: update Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::getState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::sendMpdCommand status Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 177 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 175 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 7 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 6 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 5 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: info: sendMpdCommand status took 4 milliseconds Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: Command Router : Notfying DB Updatefalse Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::Close All Modals sent Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: ControllerMpd::parseState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:25 minidsp-shd volumio[714]: 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} Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: No code Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:25 minidsp-shd volumio[714]: 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} Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: No code Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ControllerMpd::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::servicePushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CorePlayQueue::getTrack 0 Apr 15 19:45:25 minidsp-shd volumio[714]: 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} Apr 15 19:45:25 minidsp-shd volumio[714]: verbose: CURRENT POSITION 0 Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState stateService stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::syncState currentStatus stop Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: No code Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreStateMachine::pushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: CoreCommandRouter::volumioPushState Apr 15 19:45:25 minidsp-shd volumio[714]: info: ------------------------------ 339ms Apr 15 19:45:25 minidsp-shd volumio[714]: info: ------------------------------ 171ms Apr 15 19:45:25 minidsp-shd volumio[714]: info: ------------------------------ 169ms Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:25 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Apr 15 19:45:26 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 19:45:29 minidsp-shd ntpd[694]: 92.62.231.58 local addr 192.168.1.100 -> Apr 15 19:45:30 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 19:45:30 minidsp-shd volumio[714]: info: CURURI: artists:// Apr 15 19:45:30 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:45:32 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 19:45:32 minidsp-shd volumio[714]: info: CURURI: music-library Apr 15 19:45:32 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:45:35 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 19:45:35 minidsp-shd volumio[714]: info: CURURI: music-library/NAS Apr 15 19:45:35 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:45:36 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 19:45:36 minidsp-shd volumio[714]: info: CURURI: music-library/NAS/nas Apr 15 19:45:36 minidsp-shd volumio[714]: error: Failed LSINFO: null Apr 15 19:45:37 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:45:43 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 19:45:44 minidsp-shd volumio[714]: info: CURURI: music-library Apr 15 19:45:44 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:45:58 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 19:45:58 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:45:59 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 19:46:00 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:46:02 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 19:46:02 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:46:04 minidsp-shd ntpd[694]: 31.31.77.84 local addr 192.168.1.100 -> Apr 15 19:46:05 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 19:46:05 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:46:14 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 19:46:14 minidsp-shd volumio[714]: info: Preload queue cleared Apr 15 19:46:15 minidsp-shd volumio[714]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 19:46:15 minidsp-shd volumio[714]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 19:46:15 minidsp-shd volumio[714]: TypeError: Cannot read property 'length' of undefined Apr 15 19:46:15 minidsp-shd volumio[714]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 15 19:46:15 minidsp-shd volumio[714]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 15 19:46:15 minidsp-shd volumio[714]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 15 19:46:15 minidsp-shd volumio[714]: at Parser.emit (events.js:400:28) Apr 15 19:46:15 minidsp-shd volumio[714]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 15 19:46:15 minidsp-shd volumio[714]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 15 19:46:15 minidsp-shd volumio[714]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 15 19:46:15 minidsp-shd volumio[714]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 15 19:46:15 minidsp-shd volumio[714]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 15 19:46:15 minidsp-shd volumio[714]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 15 19:46:15 minidsp-shd volumio[714]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 15 19:46:15 minidsp-shd volumio[714]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 15 19:46:15 minidsp-shd volumio[714]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 15 19:46:15 minidsp-shd volumio[714]: at IncomingMessage.emit (events.js:412:35) Apr 15 19:46:15 minidsp-shd volumio[714]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 15 19:46:15 minidsp-shd volumio[714]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 15 19:46:15 minidsp-shd volumio[714]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 19:46:16 minidsp-shd sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-15 19:45 Apr 15 19:46:16 minidsp-shd sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"