Aug 27 22:42:08 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 22:42:19 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 22:42:19 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 22:42:21 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 22:42:21 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand rescan Aug 27 22:42:21 lmf volumio[1030]: info: sendMpdCommand rescan took 3 milliseconds Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:21 lmf volumio[1030]: info: Aug 27 22:42:21 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:21 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:21 lmf volumio[1030]: info: Aug 27 22:42:21 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:21 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:21 lmf volumio[1030]: info: Aug 27 22:42:21 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:21 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:21 lmf volumio[1030]: info: sendMpdCommand status took 6 milliseconds Aug 27 22:42:21 lmf volumio[1030]: info: sendMpdCommand status took 5 milliseconds Aug 27 22:42:21 lmf volumio[1030]: info: sendMpdCommand status took 4 milliseconds Aug 27 22:42:21 lmf volumio[1030]: info: sendMpdCommand status took 3 milliseconds Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:21 lmf volumio[1030]: info: Command Router : Notfying DB Updatetrue Aug 27 22:42:21 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:21 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:22 lmf volumio[1030]: info: Command Router : Notfying DB Updatetrue Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:22 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:22 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: 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} Aug 27 22:42:22 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: No code Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: 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} Aug 27 22:42:22 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: No code Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: ------------------------------ 649ms Aug 27 22:42:22 lmf volumio[1030]: info: ------------------------------ 654ms Aug 27 22:42:22 lmf volumio[1030]: info: sendMpdCommand status took 654 milliseconds Aug 27 22:42:22 lmf volumio[1030]: info: sendMpdCommand status took 654 milliseconds Aug 27 22:42:22 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:22 lmf volumio[1030]: info: Command Router : Notfying DB Updatetrue Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:22 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:22 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: 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} Aug 27 22:42:22 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: No code Aug 27 22:42:22 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:22 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:22 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:22 lmf volumio[1030]: info: ------------------------------ 788ms Aug 27 22:42:23 lmf volumio[1030]: info: MPD Database updated - AlbumList cache refreshed Aug 27 22:42:23 lmf volumio[1030]: info: Aug 27 22:42:23 lmf volumio[1030]: ---------------------------- MPD announces state update: database Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: Aug 27 22:42:23 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: MPD Database updated - AlbumList cache refreshed Aug 27 22:42:23 lmf volumio[1030]: info: Aug 27 22:42:23 lmf volumio[1030]: ---------------------------- MPD announces state update: database Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: Aug 27 22:42:23 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: MPD Database updated - AlbumList cache refreshed Aug 27 22:42:23 lmf volumio[1030]: info: Aug 27 22:42:23 lmf volumio[1030]: ---------------------------- MPD announces state update: database Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: Aug 27 22:42:23 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 99 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 99 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 99 milliseconds Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: info: Command Router : Notfying DB Updatefalse Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: 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} Aug 27 22:42:23 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: No code Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: 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} Aug 27 22:42:23 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: No code Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: ------------------------------ 218ms Aug 27 22:42:23 lmf volumio[1030]: info: ------------------------------ 219ms Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 247 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 246 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 245 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 218 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 218 milliseconds Aug 27 22:42:23 lmf volumio[1030]: info: sendMpdCommand status took 217 milliseconds Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: info: Command Router : Notfying DB Updatefalse Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: info: Command Router : Notfying DB Updatefalse Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: 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} Aug 27 22:42:23 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: No code Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: 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} Aug 27 22:42:23 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: No code Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: 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} Aug 27 22:42:23 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: No code Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: 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} Aug 27 22:42:23 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: No code Aug 27 22:42:23 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:23 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:23 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:23 lmf volumio[1030]: info: ------------------------------ 366ms Aug 27 22:42:23 lmf volumio[1030]: info: ------------------------------ 365ms Aug 27 22:42:23 lmf volumio[1030]: info: ------------------------------ 336ms Aug 27 22:42:23 lmf volumio[1030]: info: ------------------------------ 337ms Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand update Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand update took 4 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 5 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 4 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 4 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 3 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 4 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 3 milliseconds Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: Command Router : Notfying DB Updatetrue Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: Command Router : Notfying DB Updatetrue Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: Command Router : Notfying DB Updatetrue Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 349ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 348ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 348ms Aug 27 22:42:24 lmf volumio[1030]: info: MPD Database updated - AlbumList cache refreshed Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: database Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: MPD Database updated - AlbumList cache refreshed Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: database Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: MPD Database updated - AlbumList cache refreshed Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: database Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: Aug 27 22:42:24 lmf volumio[1030]: ---------------------------- MPD announces state update: update Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::getState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 28 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 27 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 26 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 25 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 24 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 23 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 21 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 21 milliseconds Aug 27 22:42:24 lmf volumio[1030]: info: sendMpdCommand status took 21 milliseconds Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: Command Router : Notfying DB Updatefalse Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: Command Router : Notfying DB Updatefalse Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: Command Router : Notfying DB Updatefalse Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: verbose: ControllerMpd::parseState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ControllerMpd::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::servicePushState Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: 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} Aug 27 22:42:24 lmf volumio[1030]: verbose: CURRENT POSITION 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState stateService stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: No code Aug 27 22:42:24 lmf volumio[1030]: info: CoreStateMachine::pushState Aug 27 22:42:24 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:24 lmf volumio[1030]: info: CoreCommandRouter::volumioPushState Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 137ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 137ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 134ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 134ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 131ms Aug 27 22:42:24 lmf volumio[1030]: info: ------------------------------ 131ms Aug 27 22:42:25 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 22:42:29 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 22:42:31 lmf volumio[1030]: info: CoreCommandRouter::volumioGetState Aug 27 22:42:31 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:44 lmf volumio[1030]: info: CoreCommandRouter::volumioGetState Aug 27 22:42:44 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:50 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 22:42:50 lmf volumio[1030]: info: CURURI: music-library Aug 27 22:42:50 lmf volumio[1030]: info: Preload queue cleared Aug 27 22:42:52 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 22:42:52 lmf volumio[1030]: info: CURURI: music-library/INTERNAL Aug 27 22:42:52 lmf volumio[1030]: info: Preload queue cleared Aug 27 22:42:52 lmf volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 22:42:52 lmf volumio[1030]: info: CURURI: music-library/INTERNAL/cd Aug 27 22:42:52 lmf volumio[1030]: info: Preload queue cleared Aug 27 22:42:52 lmf volumio[1030]: info: Preloading song: music-library/INTERNAL/cd/明天会更好 - 蔡琴&余天&苏芮&潘越云&甄妮&李建复&林慧萍&王芷蕾&黄莺莺&洪荣宏&陈淑桦&娃娃&王梦麟&李珮菁&费玉清&齐豫&郑怡&江蕙&杨林&齐秦.flac Aug 27 22:42:52 lmf volumio[1030]: info: Exploding uri music-library/INTERNAL/cd/明天会更好 - 蔡琴&余天&苏芮&潘越云&甄妮&李建复&林慧萍&王芷蕾&黄莺莺&洪荣宏&陈淑桦&娃娃&王梦麟&李珮菁&费玉清&齐豫&郑怡&江蕙&杨林&齐秦.flac in service mpd Aug 27 22:42:52 lmf volumio[1030]: info: ALBUMART /albumart?cacheid=715&web=%E8%94%A1%E7%90%B4%2F%E4%BD%99%E5%A4%A9%2F%E8%8B%8F%E8%8A%AE%2F%E6%BD%98%E8%B6%8A%E4%BA%91%2F%E7%94%84%E5%A6%AE%2F%E6%9D%8E%E5%BB%BA%E5%A4%8D%2F%E6%9E%97%E6%85%A7%E8%90%8D%2F%E7%8E%8B%E8%8A%B7%E8%95%BE%2F%E9%BB%84%E8%8E%BA%E8%8E%BA%2F%E6%B4%AA%E8%8D%A3%E5%AE%8F%2F%E9%99%88%E6%B7%91%E6%A1%A6%2F%E5%A8%83%E5%A8%83%2F%E7%8E%8B%E6%A2%A6%E9%BA%9F%2F%E6%9D%8E%E7%8F%AE%E8%8F%81%2F%E8%B4%B9%E7%8E%89%E6%B8%85%2F%E9%BD%90%E8%B1%AB%2F%E9%83%91%E6%80%A1%2F%E6%B1%9F%E8%95%99%2F%E6%9D%A8%E6%9E%97%2F%E9%BD%90%E7%A7%A6/%E6%98%8E%E5%A4%A9%E4%BC%9A%E6%9B%B4%E5%A5%BD/extralarge&path=%2Fmnt%2FINTERNAL%2Fcd%2F%E6%98%8E%E5%A4%A9%E4%BC%9A%E6%9B%B4%E5%A5%BD%20-%20%E8%94%A1%E7%90%B4%26%E4%BD%99%E5%A4%A9%26%E8%8B%8F%E8%8A%AE%26%E6%BD%98%E8%B6%8A%E4%BA%91%26%E7%94%84%E5%A6%AE%26%E6%9D%8E%E5%BB%BA%E5%A4%8D%26%E6%9E%97%E6%85%A7%E8%90%8D%26%E7%8E%8B%E8%8A%B7%E8%95%BE%26%E9%BB%84%E8%8E%BA%E8%8E%BA%26%E6%B4%AA%E8%8D%A3%E5%AE%8F%26%E9%99%88%E6%B7%91%E6%A1%A6%26%E5%A8%83%E5%A8%83%26%E7%8E%8B%E6%A2%A6%E9%BA%9F%26%E6%9D%8E%E7%8F%AE%E8%8F%81%26%E8%B4%B9%E7%8E%89%E6%B8%85%26%E9%BD%90%E8%B1%AB%26%E9%83%91%E6%80%A1%26%E6%B1%9F%E8%95%99%26%E6%9D%A8%E6%9E%97%26%E9%BD%90%E7%A7%A6.flac&metadata=false Aug 27 22:42:52 lmf volumio[1030]: info: URI /mnt/INTERNAL/cd/明天会更好 - 蔡琴&余天&苏芮&潘越云&甄妮&李建复&林慧萍&王芷蕾&黄莺莺&洪荣宏&陈淑桦&娃娃&王梦麟&李珮菁&费玉清&齐豫&郑怡&江蕙&杨林&齐秦.flac Aug 27 22:42:58 lmf volumio[1030]: info: Preload queue cleared Aug 27 22:42:58 lmf volumio[1030]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::ClearQueue Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::stop Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::clearPlayQueue Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::saveQueue Aug 27 22:42:58 lmf volumio[1030]: info: CoreCommandRouter::volumioPushQueue Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::addQueueItems Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::addQueueItems Aug 27 22:42:58 lmf volumio[1030]: info: Preload queue cleared Aug 27 22:42:58 lmf volumio[1030]: info: Adding Item to queue: music-library/INTERNAL/cd/明天会更好 - 蔡琴&余天&苏芮&潘越云&甄妮&李建复&林慧萍&王芷蕾&黄莺莺&洪荣宏&陈淑桦&娃娃&王梦麟&李珮菁&费玉清&齐豫&郑怡&江蕙&杨林&齐秦.flac Aug 27 22:42:58 lmf volumio[1030]: info: Using cached record of: music-library/INTERNAL/cd/明天会更好 - 蔡琴&余天&苏芮&潘越云&甄妮&李建复&林慧萍&王芷蕾&黄莺莺&洪荣宏&陈淑桦&娃娃&王梦麟&李珮菁&费玉清&齐豫&郑怡&江蕙&杨林&齐秦.flac Aug 27 22:42:58 lmf volumio[1030]: info: CoreCommandRouter::volumioPushQueue Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::saveQueue Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::updateTrackBlock Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::getTrackBlock Aug 27 22:42:58 lmf volumio[1030]: info: CoreCommandRouter::volumioPlay Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::play index 0 Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::addQueueItems Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::addQueueItems Aug 27 22:42:58 lmf volumio[1030]: info: Preload queue cleared Aug 27 22:42:58 lmf volumio[1030]: info: Adding Item to queue: music-library/INTERNAL/cd/刘颖《乌兰巴托的夜》1：1白金直刻头版限量编号 [WAV+CUE] Aug 27 22:42:58 lmf volumio[1030]: info: Exploding uri music-library/INTERNAL/cd/刘颖《乌兰巴托的夜》1：1白金直刻头版限量编号 [WAV+CUE] in service mpd Aug 27 22:42:58 lmf volumio[1030]: info: Adding Item to queue: music-library/INTERNAL/cd/唐媛 - 媛梦 星凯文化 - 2025 - WAV+CUE Aug 27 22:42:58 lmf volumio[1030]: info: Exploding uri music-library/INTERNAL/cd/唐媛 - 媛梦 星凯文化 - 2025 - WAV+CUE in service mpd Aug 27 22:42:58 lmf volumio[1030]: info: Adding Item to queue: music-library/INTERNAL/cd/姚璎格《新歌》抓WAV+CUE] Aug 27 22:42:58 lmf volumio[1030]: info: Exploding uri music-library/INTERNAL/cd/姚璎格《新歌》抓WAV+CUE] in service mpd Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::stop Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::play index undefined Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:58 lmf volumio[1030]: info: CoreStateMachine::startPlaybackTimer Aug 27 22:42:58 lmf volumio[1030]: info: CorePlayQueue::getTrack 0 Aug 27 22:42:58 lmf volumio[1030]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/cd/明天会更好 - 蔡琴&余天&苏芮&潘越云&甄妮&李建复&林慧萍&王芷蕾&黄莺莺&洪荣宏&陈淑桦&娃娃&王梦麟&李珮菁&费玉清&齐豫&郑怡&江蕙&杨林&齐秦.flac Aug 27 22:42:58 lmf volumio[1030]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 22:42:58 lmf volumio[1030]: info: ALBUMART /albumart?cacheid=715&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fcd%2F%E5%88%98%E9%A2%96%E3%80%8A%E4%B9%8C%E5%85%B0%E5%B7%B4%E6%89%98%E7%9A%84%E5%A4%9C%E3%80%8B1%EF%BC%9A1%E7%99%BD%E9%87%91%E7%9B%B4%E5%88%BB%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%20%5BWAV%2BCUE%5D%2F%E5%88%98%E9%A2%96%20-%20%E4%B9%8C%E5%85%B0%E5%B7%B4%E6%89%98%E7%9A%84%E5%A4%9C.wav&metadata=false Aug 27 22:42:58 lmf volumio[1030]: info: URI /mnt/INTERNAL/cd/刘颖《乌兰巴托的夜》1：1白金直刻头版限量编号 [WAV+CUE]/刘颖 - 乌兰巴托的夜.wav Aug 27 22:42:58 lmf volumio[1030]: info: ALBUMART /albumart?cacheid=715&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fcd%2F%E5%94%90%E5%AA%9B%20-%20%E5%AA%9B%E6%A2%A6%20%E6%98%9F%E5%87%AF%E6%96%87%E5%8C%96%20-%202025%20-%20WAV%2BCUE%2F%E5%94%90%E5%AA%9B%20-%20%E5%AA%9B%E6%A2%A6.wav&metadata=false Aug 27 22:42:58 lmf volumio[1030]: info: URI /mnt/INTERNAL/cd/唐媛 - 媛梦 星凯文化 - 2025 - WAV+CUE/唐媛 - 媛梦.wav Aug 27 22:42:58 lmf volumio[1030]: info: ALBUMART /albumart?cacheid=715&web=/%E6%96%B0%E6%AD%8C%E5%8E%9F%E5%88%9B%E5%A4%A7%E7%A2%9F%2024K%E9%87%91%E7%A2%9F%E9%99%90%E9%87%8F/extralarge&path=%2Fmnt%2FINTERNAL%2Fcd%2F%E5%A7%9A%E7%92%8E%E6%A0%BC%E3%80%8A%E6%96%B0%E6%AD%8C%E3%80%8B%E6%8A%93WAV%2BCUE%5D%2F%E5%A7%9A%E7%92%8E%E6%A0%BC%C2%B7%E6%96%B0%E6%AD%8C%E5%8E%9F%E5%88%9B%E5%A4%A7%E7%A2%9F.cue&metadata=false Aug 27 22:42:58 lmf volumio[1030]: info: URI /mnt/INTERNAL/cd/姚璎格《新歌》抓WAV+CUE]/姚璎格·新歌原创大碟.cue Aug 27 22:42:58 lmf volumio[1030]: info: ALBUMART /albumart?cacheid=715&web=/%E6%96%B0%E6%AD%8C%E5%8E%9F%E5%88%9B%E5%A4%A7%E7%A2%9F%2024K%E9%87%91%E7%A2%9F%E9%99%90%E9%87%8F/extralarge&path=%2Fmnt%2FINTERNAL%2Fcd%2F%E5%A7%9A%E7%92%8E%E6%A0%BC%E3%80%8A%E6%96%B0%E6%AD%8C%E3%80%8B%E6%8A%93WAV%2BCUE%5D%2F%E5%A7%9A%E7%92%8E%E6%A0%BC%C2%B7%E6%96%B0%E6%AD%8C%E5%8E%9F%E5%88%9B%E5%A4%A7%E7%A2%9F.cue&metadata=false Aug 27 22:42:58 lmf volumio[1030]: info: URI /mnt/INTERNAL/cd/姚璎格《新歌》抓WAV+CUE]/姚璎格·新歌原创大碟.cue Aug 27 22:42:58 lmf volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:42:58 lmf volumio[1030]: Error: Unable to resolve or reject the same promise twice Aug 27 22:42:58 lmf volumio[1030]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 27 22:42:58 lmf volumio[1030]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 27 22:42:58 lmf volumio[1030]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 27 22:42:58 lmf volumio[1030]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 27 22:42:58 lmf volumio[1030]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 27 22:42:58 lmf volumio[1030]: at Socket.emit (node:events:514:28) Aug 27 22:42:58 lmf volumio[1030]: at addChunk (node:internal/streams/readable:343:12) Aug 27 22:42:58 lmf volumio[1030]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 27 22:42:58 lmf volumio[1030]: at Readable.push (node:internal/streams/readable:253:10) Aug 27 22:42:58 lmf volumio[1030]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 27 22:42:58 lmf volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:43:00 lmf sudo[6554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 22:42' Aug 27 22:43:00 lmf sudo[6554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"