-- Logs begin at Fri 2025-05-02 14:27:20 UTC, end at Fri 2025-05-02 14:43:07 UTC. -- May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand rescan May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand rescan took 6 milliseconds May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 15 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 9 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 8 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 8 milliseconds May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:00 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: No code May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:00 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: No code May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: ------------------------------ 419ms May 02 14:42:00 volumio volumio[3019]: info: ------------------------------ 413ms May 02 14:42:00 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 410 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 409 milliseconds May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:00 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: No code May 02 14:42:00 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:00 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:00 volumio volumio[3019]: info: ------------------------------ 534ms May 02 14:42:00 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: May 02 14:42:00 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 232 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 231 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 232 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 106 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 106 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 105 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 104 milliseconds May 02 14:42:00 volumio volumio[3019]: info: sendMpdCommand status took 103 milliseconds May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:00 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:00 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:01 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:01 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:01 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:01 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:01 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:01 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: 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} May 02 14:42:01 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: No code May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: 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} May 02 14:42:01 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: No code May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: 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} May 02 14:42:01 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: No code May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: 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} May 02 14:42:01 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: No code May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: 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} May 02 14:42:01 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: No code May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: 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} May 02 14:42:01 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: No code May 02 14:42:01 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:01 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:01 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:01 volumio volumio[3019]: info: ------------------------------ 792ms May 02 14:42:01 volumio volumio[3019]: info: ------------------------------ 793ms May 02 14:42:01 volumio volumio[3019]: info: ------------------------------ 668ms May 02 14:42:01 volumio volumio[3019]: info: ------------------------------ 670ms May 02 14:42:01 volumio volumio[3019]: info: ------------------------------ 672ms May 02 14:42:01 volumio volumio[3019]: info: ------------------------------ 674ms May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand rescan May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand rescan took 4 milliseconds May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand status took 7 milliseconds May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand status took 6 milliseconds May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand status took 5 milliseconds May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand status took 4 milliseconds May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand status took 3 milliseconds May 02 14:42:02 volumio volumio[3019]: info: sendMpdCommand status took 2 milliseconds May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:02 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:02 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:02 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:02 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: No code May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:02 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: No code May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:02 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: No code May 02 14:42:02 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:02 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:02 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:02 volumio volumio[3019]: info: ------------------------------ 420ms May 02 14:42:02 volumio volumio[3019]: info: ------------------------------ 418ms May 02 14:42:02 volumio volumio[3019]: info: ------------------------------ 417ms May 02 14:42:02 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:02 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:02 volumio volumio[3019]: info: May 02 14:42:02 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:03 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:03 volumio volumio[3019]: info: May 02 14:42:03 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:03 volumio volumio[3019]: info: May 02 14:42:03 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 92 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 91 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 91 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 76 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 73 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 69 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 65 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 61 milliseconds May 02 14:42:03 volumio volumio[3019]: info: sendMpdCommand status took 61 milliseconds May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: 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} May 02 14:42:03 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: No code May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: 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} May 02 14:42:03 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: No code May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: 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} May 02 14:42:03 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: No code May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: 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} May 02 14:42:03 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: No code May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: 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} May 02 14:42:03 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: No code May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: 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} May 02 14:42:03 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: No code May 02 14:42:03 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:03 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:03 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:03 volumio volumio[3019]: info: ------------------------------ 684ms May 02 14:42:03 volumio volumio[3019]: info: ------------------------------ 685ms May 02 14:42:03 volumio volumio[3019]: info: ------------------------------ 669ms May 02 14:42:03 volumio volumio[3019]: info: ------------------------------ 671ms May 02 14:42:03 volumio volumio[3019]: info: ------------------------------ 661ms May 02 14:42:03 volumio volumio[3019]: info: ------------------------------ 662ms May 02 14:42:04 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:07 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 6 May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:07 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:07 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::volumioGetVisibleSources May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:07 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 14:42:07 volumio volumio[3019]: info: Received Get System Info May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:07 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:07 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:07 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:07 volumio volumio[3019]: info: Listing playlists May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 14:42:07 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 02 14:42:07 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand rescan May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand rescan took 5 milliseconds May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 19 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 18 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 17 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 11 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 10 milliseconds May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ------------------------------ 578ms May 02 14:42:08 volumio volumio[3019]: info: ------------------------------ 577ms May 02 14:42:08 volumio volumio[3019]: info: ------------------------------ 570ms May 02 14:42:08 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: May 02 14:42:08 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 74 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 72 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 71 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 69 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 68 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 66 milliseconds May 02 14:42:08 volumio volumio[3019]: info: sendMpdCommand status took 65 milliseconds May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: 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} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: 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} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: 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} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: 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} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: 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} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: 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} May 02 14:42:08 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:08 volumio volumio[3019]: info: No code May 02 14:42:08 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:08 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:08 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:09 volumio volumio[3019]: info: ------------------------------ 484ms May 02 14:42:09 volumio volumio[3019]: info: ------------------------------ 486ms May 02 14:42:09 volumio volumio[3019]: info: ------------------------------ 482ms May 02 14:42:09 volumio volumio[3019]: info: ------------------------------ 479ms May 02 14:42:09 volumio volumio[3019]: info: ------------------------------ 481ms May 02 14:42:09 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 5 May 02 14:42:20 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 4 May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::volumioGetVisibleSources May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:20 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 14:42:20 volumio volumio[3019]: info: Received Get System Info May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:20 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:20 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:20 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:20 volumio volumio[3019]: info: Listing playlists May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:20 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:20 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:20 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:21 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 5 May 02 14:42:21 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:21 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:21 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 02 14:42:21 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 02 14:42:21 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 14:42:24 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:42 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 4 May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::volumioGetVisibleSources May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:42 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 14:42:42 volumio volumio[3019]: info: Received Get System Info May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:42 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:42 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:42 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:42 volumio volumio[3019]: info: Listing playlists May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:42 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:42 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:42 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 14:42:44 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand rescan May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand rescan took 13 milliseconds May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 20 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 16 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 15 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 10 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 9 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 8 milliseconds May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: Command Router : Notfying DB Updatetrue May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ------------------------------ 464ms May 02 14:42:47 volumio volumio[3019]: info: ------------------------------ 459ms May 02 14:42:47 volumio volumio[3019]: info: ------------------------------ 454ms May 02 14:42:47 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: MPD Database updated - AlbumList cache refreshed May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: database May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: May 02 14:42:47 volumio volumio[3019]: ---------------------------- MPD announces state update: update May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::getState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::sendMpdCommand status May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 73 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 73 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 72 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 69 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 66 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 65 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 60 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 59 milliseconds May 02 14:42:47 volumio volumio[3019]: info: sendMpdCommand status took 58 milliseconds May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: Command Router : Notfying DB Updatefalse May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::Close All Modals sent May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: verbose: ControllerMpd::parseState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: 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} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: 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} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: 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} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: 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} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: 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} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: ControllerMpd::pushState May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::servicePushState May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: 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} May 02 14:42:47 volumio volumio[3019]: verbose: CURRENT POSITION 0 May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState stateService stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::syncState currentStatus stop May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:47 volumio volumio[3019]: info: No code May 02 14:42:47 volumio volumio[3019]: info: CoreStateMachine::pushState May 02 14:42:47 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:47 volumio volumio[3019]: info: CoreCommandRouter::volumioPushState May 02 14:42:48 volumio volumio[3019]: info: ------------------------------ 449ms May 02 14:42:48 volumio volumio[3019]: info: ------------------------------ 450ms May 02 14:42:48 volumio volumio[3019]: info: ------------------------------ 447ms May 02 14:42:48 volumio volumio[3019]: info: ------------------------------ 457ms May 02 14:42:48 volumio volumio[3019]: info: ------------------------------ 453ms May 02 14:42:48 volumio volumio[3019]: info: ------------------------------ 454ms May 02 14:42:48 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 5 May 02 14:42:48 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:48 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 02 14:42:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 02 14:42:48 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:53 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:53 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:53 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:53 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:53 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:53 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:53 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 4 May 02 14:42:54 volumio volumio[3019]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.11 UA: okhttp/4.9.2 Total Clients: 5 May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::volumioGetVisibleSources May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:54 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 14:42:54 volumio volumio[3019]: info: Received Get System Info May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 14:42:54 volumio volumio[3019]: info: Discovery: Getting this device information May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:54 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::volumioGetState May 02 14:42:54 volumio volumio[3019]: info: CorePlayQueue::getTrack 0 May 02 14:42:54 volumio volumio[3019]: info: Listing playlists May 02 14:42:54 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 14:42:57 volumio volumio[3019]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 14:43:06 volumio volumio[3019]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 14:43:06 volumio volumio[3019]: Error: connect ETIMEDOUT 208.101.60.87:80 May 02 14:43:06 volumio volumio[3019]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 02 14:43:06 volumio volumio[3019]: errno: -110, May 02 14:43:06 volumio volumio[3019]: code: 'ETIMEDOUT', May 02 14:43:06 volumio volumio[3019]: syscall: 'connect', May 02 14:43:06 volumio volumio[3019]: address: '208.101.60.87', May 02 14:43:06 volumio volumio[3019]: port: 80 May 02 14:43:06 volumio volumio[3019]: } May 02 14:43:06 volumio volumio[3019]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 14:43:07 volumio sudo[3397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 14:42 May 02 14:43:07 volumio sudo[3397]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"