-- Logs begin at Thu 2024-12-26 23:12:10 CST, end at Thu 2025-05-08 18:14:08 CST. -- May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:01 volumio volumio[1002]: info: Retrieving Cloud Streaming UI May 08 18:13:01 volumio volumio[1002]: info: Getting Tidal Cloud Configuration May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:01 volumio volumio[1002]: info: Getting Qobuz Cloud Configuration May 08 18:13:01 volumio volumio[1002]: info: Asking plugin for UI Config May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:01 volumio volumio[1002]: info: Getting Spotify Cloud Configuration May 08 18:13:01 volumio volumio[1002]: info: Asking plugin for UI Config May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:01 volumio volumio[1002]: info: Saving Spotify Acccount May 08 18:13:01 volumio volumio[1002]: info: Got Tidal Cloud Configuration May 08 18:13:01 volumio volumio[1002]: info: Got it May 08 18:13:01 volumio volumio[1002]: info: Got it May 08 18:13:01 volumio volumio[1002]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:01 volumio volumio[1002]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand update took 3 milliseconds May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 153ms May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 150ms May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 76ms May 08 18:13:04 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: May 08 18:13:04 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 93 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 93 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 93 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 20 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 20 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 20 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:04 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: 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 08 18:13:04 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: No code May 08 18:13:04 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:04 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:04 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 360ms May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 360ms May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 288ms May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 288ms May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 275ms May 08 18:13:04 volumio volumio[1002]: info: ------------------------------ 276ms May 08 18:13:05 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:05 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:05 volumio volumio[1002]: info: May 08 18:13:05 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:05 volumio volumio[1002]: info: sendMpdCommand update took 4 milliseconds May 08 18:13:05 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:05 volumio volumio[1002]: info: May 08 18:13:05 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:05 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:05 volumio volumio[1002]: info: May 08 18:13:05 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:05 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:05 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 191ms May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 189ms May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 188ms May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 63ms May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 78 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 78 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 2 milliseconds May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 204ms May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 132ms May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand update took 4 milliseconds May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 16 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 14 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 13 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 13 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 12 milliseconds May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 155ms May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 153ms May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 144 milliseconds May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: 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 08 18:13:06 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: No code May 08 18:13:06 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:06 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:06 volumio volumio[1002]: info: ------------------------------ 180ms May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: May 08 18:13:06 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:06 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 47 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 46 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:06 volumio volumio[1002]: info: sendMpdCommand status took 2 milliseconds May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:06 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:06 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 242ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 200ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 199ms May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand update took 4 milliseconds May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 169ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 167ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 165ms May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 25 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 24 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 2 milliseconds May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 174ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 154ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 153ms May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand rescan May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand rescan took 3 milliseconds May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 188ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 187ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 185ms May 08 18:13:07 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 83ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 83ms May 08 18:13:07 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: May 08 18:13:07 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 107 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 106 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 105 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 10 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds May 08 18:13:07 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: 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 08 18:13:07 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: No code May 08 18:13:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 249ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 248ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 151ms May 08 18:13:07 volumio volumio[1002]: info: ------------------------------ 152ms May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: May 08 18:13:08 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand update took 4 milliseconds May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: May 08 18:13:08 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: May 08 18:13:08 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:08 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:08 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:08 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: 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 08 18:13:08 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: No code May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: 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 08 18:13:08 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: No code May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: 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 08 18:13:08 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: No code May 08 18:13:08 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:08 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:08 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:08 volumio volumio[1002]: info: ------------------------------ 160ms May 08 18:13:08 volumio volumio[1002]: info: ------------------------------ 158ms May 08 18:13:08 volumio volumio[1002]: info: ------------------------------ 157ms May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: May 08 18:13:08 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: May 08 18:13:08 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: May 08 18:13:08 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:08 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 18 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 16 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:08 volumio volumio[1002]: info: sendMpdCommand status took 2 milliseconds May 08 18:13:08 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:09 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:09 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:09 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:09 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:09 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:09 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:09 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:09 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:09 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: 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 08 18:13:09 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: No code May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: 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 08 18:13:09 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: No code May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: 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 08 18:13:09 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: No code May 08 18:13:09 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:09 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:09 volumio volumio[1002]: info: ------------------------------ 169ms May 08 18:13:09 volumio volumio[1002]: info: ------------------------------ 157ms May 08 18:13:09 volumio volumio[1002]: info: ------------------------------ 156ms May 08 18:13:09 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:13 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:25 volumio kernel: usb 1-1.2: USB disconnect, device number 5 May 08 18:13:25 volumio volumio[1002]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-YHZY_USB_MASS_STORAGE_4194043011-0:0-part1 /dev/disk/by-uuid/608ACA898ACA5B64 /dev/disk/by-label/老毛桃U盘 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/836dc76f-01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_FS_LABEL":"老毛桃U盘","ID_FS_LABEL_ENC":"老毛桃U盘","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"608ACA898ACA5B64","ID_FS_UUID_ENC":"608ACA898ACA5B64","ID_INSTANCE":"0:0","ID_MODEL":"USB_MASS_STORAGE","ID_MODEL_ENC":"USB\\x20MASS\\x20STORAGE","ID_MODEL_ID":"1234","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1961984","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"119996416","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"836dc76f-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"836dc76f","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"YHZY_USB_MASS_STORAGE_4194043011-0:0","ID_SERIAL_SHORT":"4194043011","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"YHZY","ID_VENDOR_ENC":"YHZY\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0000","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"1998","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"327841226"} May 08 18:13:25 volumio sudo[1841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 08 18:13:25 volumio sudo[1841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 18:13:25 volumio ntfs-3g[1710]: Unmounting /dev/sda1 (老毛桃U盘) May 08 18:13:25 volumio ntfs-3g[1710]: Failed to sync device /dev/sda1: Input/output error May 08 18:13:25 volumio ntfs-3g[1710]: Failed to close volume /dev/sda1: Input/output error May 08 18:13:25 volumio sudo[1841]: pam_unix(sudo:session): session closed for user root May 08 18:13:25 volumio systemd[1]: media-\xe8\x80\x81\xe6\xaf\x9b\xe6\xa1\x83U\xe7\x9b\x98.mount: Succeeded. May 08 18:13:25 volumio volumio[1002]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/EFI /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-partuuid/836dc76f-02 /dev/disk/by-id/usb-YHZY_USB_MASS_STORAGE_4194043011-0:0-part2 /dev/disk/by-uuid/1105-1226","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_FS_LABEL":"EFI","ID_FS_LABEL_ENC":"EFI","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"1105-1226","ID_FS_UUID_ENC":"1105-1226","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"USB_MASS_STORAGE","ID_MODEL_ENC":"USB\\x20MASS\\x20STORAGE","ID_MODEL_ID":"1234","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"121958400","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"917503","ID_PART_ENTRY_TYPE":"0x1b","ID_PART_ENTRY_UUID":"836dc76f-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"836dc76f","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"2.00","ID_SERIAL":"YHZY_USB_MASS_STORAGE_4194043011-0:0","ID_SERIAL_SHORT":"4194043011","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"YHZY","ID_VENDOR_ENC":"YHZY\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0000","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"1999","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"327841297"} May 08 18:13:26 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 May 08 18:13:26 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 18:13:26 volumio systemd[1]: media-EFI.mount: Succeeded. May 08 18:13:26 volumio kernel: FAT-fs (sda2): unable to read boot sector to mark fs as dirty May 08 18:13:26 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root May 08 18:13:26 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache May 08 18:13:26 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK May 08 18:13:29 volumio volumio[1002]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/老毛桃U盘 May 08 18:13:30 volumio volumio[1002]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/EFI May 08 18:13:35 volumio kernel: usb 1-1.3: new high-speed USB device number 6 using dwc_otg May 08 18:13:36 volumio kernel: usb 1-1.3: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 May 08 18:13:36 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 May 08 18:13:36 volumio kernel: usb 1-1.3: Product: Mass Storage Device May 08 18:13:36 volumio kernel: usb 1-1.3: Manufacturer: Generic May 08 18:13:36 volumio kernel: usb 1-1.3: SerialNumber: 121220160204 May 08 18:13:36 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected May 08 18:13:36 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 May 08 18:13:37 volumio kernel: scsi 0:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS May 08 18:13:37 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 May 08 18:13:37 volumio kernel: sd 0:0:0:0: [sda] 60751872 512-byte logical blocks: (31.1 GB/29.0 GiB) May 08 18:13:37 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off May 08 18:13:37 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 May 08 18:13:37 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found May 08 18:13:37 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through May 08 18:13:37 volumio kernel: sda: sda1 May 08 18:13:37 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk May 08 18:13:37 volumio volumio[1002]: info: Mounting Device RAO_32G May 08 18:13:37 volumio sudo[1858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/RAO_32G -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 08 18:13:37 volumio sudo[1858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 18:13:37 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 08 18:13:37 volumio sudo[1858]: pam_unix(sudo:session): session closed for user root May 08 18:13:37 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:45 volumio volumio[1002]: info: Retrieving Cloud Streaming UI May 08 18:13:45 volumio volumio[1002]: info: Getting Tidal Cloud Configuration May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:45 volumio volumio[1002]: info: Getting Qobuz Cloud Configuration May 08 18:13:45 volumio volumio[1002]: info: Asking plugin for UI Config May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:45 volumio volumio[1002]: info: Getting Spotify Cloud Configuration May 08 18:13:45 volumio volumio[1002]: info: Asking plugin for UI Config May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:45 volumio volumio[1002]: info: Saving Spotify Acccount May 08 18:13:45 volumio volumio[1002]: info: Got Tidal Cloud Configuration May 08 18:13:45 volumio volumio[1002]: info: Got it May 08 18:13:45 volumio volumio[1002]: info: Got it May 08 18:13:45 volumio volumio[1002]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:13:45 volumio volumio[1002]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:13:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:47 volumio volumio[1002]: info: May 08 18:13:47 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand update took 10 milliseconds May 08 18:13:47 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:47 volumio volumio[1002]: info: May 08 18:13:47 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:47 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:47 volumio volumio[1002]: info: May 08 18:13:47 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:47 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand status took 27 milliseconds May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand status took 22 milliseconds May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand status took 20 milliseconds May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand status took 16 milliseconds May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand status took 15 milliseconds May 08 18:13:47 volumio volumio[1002]: info: sendMpdCommand status took 11 milliseconds May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:47 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:47 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:47 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:47 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:47 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: 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 08 18:13:47 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: No code May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: 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 08 18:13:47 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: No code May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: 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 08 18:13:47 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: No code May 08 18:13:47 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:47 volumio volumio[1002]: info: ------------------------------ 333ms May 08 18:13:47 volumio volumio[1002]: info: ------------------------------ 325ms May 08 18:13:47 volumio volumio[1002]: info: ------------------------------ 320ms May 08 18:13:48 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:48 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:48 volumio volumio[1002]: info: sendMpdCommand update took 4 milliseconds May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand rescan May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand rescan took 3 milliseconds May 08 18:13:49 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 72 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 71 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 71 milliseconds May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: 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 08 18:13:49 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: No code May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: 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 08 18:13:49 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: No code May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: ------------------------------ 206ms May 08 18:13:49 volumio volumio[1002]: info: ------------------------------ 207ms May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 252 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 250 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 250 milliseconds May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: 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 08 18:13:49 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: No code May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: 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 08 18:13:49 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: No code May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: ------------------------------ 363ms May 08 18:13:49 volumio volumio[1002]: info: ------------------------------ 364ms May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: info: May 08 18:13:49 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 407 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 407 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 407 milliseconds May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: 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 08 18:13:49 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: No code May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: 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 08 18:13:49 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: No code May 08 18:13:49 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:49 volumio volumio[1002]: info: ------------------------------ 519ms May 08 18:13:49 volumio volumio[1002]: info: ------------------------------ 519ms May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 119 milliseconds May 08 18:13:49 volumio volumio[1002]: info: sendMpdCommand status took 116 milliseconds May 08 18:13:49 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 186ms May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:50 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 14 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 13 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 13 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 11 milliseconds May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 160ms May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 158ms May 08 18:13:50 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand rescan May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 258 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 257 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 256 milliseconds May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 361ms May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 362ms May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 240 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 232 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 231 milliseconds May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 344ms May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 345ms May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 371 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 371 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 370 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand rescan took 298 milliseconds May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 468ms May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 468ms May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 110 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 104 milliseconds May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 14 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 12 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 11 milliseconds May 08 18:13:50 volumio volumio[1002]: info: sendMpdCommand status took 11 milliseconds May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: 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 08 18:13:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: No code May 08 18:13:50 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 291ms May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 193ms May 08 18:13:50 volumio volumio[1002]: info: ------------------------------ 192ms May 08 18:13:50 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: May 08 18:13:50 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand rescan May 08 18:13:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 18:13:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand rescan May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 284 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 282 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 282 milliseconds May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 389ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 390ms May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 233 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 223 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 222 milliseconds May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 337ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 338ms May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 360 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 359 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 359 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand rescan took 326 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand rescan took 324 milliseconds May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 459ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 460ms May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 113 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 108 milliseconds May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 12 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 10 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 10 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 343ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 241ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 239ms May 08 18:13:51 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 330 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 329 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 328 milliseconds May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 414ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 414ms May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 214 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 213 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 213 milliseconds May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: 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 08 18:13:51 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: No code May 08 18:13:51 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:51 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:51 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 302ms May 08 18:13:51 volumio volumio[1002]: info: ------------------------------ 302ms May 08 18:13:51 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:51 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:51 volumio volumio[1002]: info: May 08 18:13:51 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 334 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 333 milliseconds May 08 18:13:51 volumio volumio[1002]: info: sendMpdCommand status took 332 milliseconds May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 439ms May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 440ms May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 143 milliseconds May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:52 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:52 volumio volumio[1002]: info: May 08 18:13:52 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: May 08 18:13:52 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: MPD Database updated - AlbumList cache refreshed May 08 18:13:52 volumio volumio[1002]: info: May 08 18:13:52 volumio volumio[1002]: ---------------------------- MPD announces state update: database May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: May 08 18:13:52 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 208 milliseconds May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 206 milliseconds May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 270ms May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 271ms May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 137 milliseconds May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 136 milliseconds May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 136 milliseconds May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 229ms May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 229ms May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 257 milliseconds May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 256 milliseconds May 08 18:13:52 volumio volumio[1002]: info: sendMpdCommand status took 254 milliseconds May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: 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 08 18:13:52 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: No code May 08 18:13:52 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:52 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:52 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 353ms May 08 18:13:52 volumio volumio[1002]: info: ------------------------------ 353ms May 08 18:13:53 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand update May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: May 08 18:13:56 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand update took 4 milliseconds May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: May 08 18:13:56 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: May 08 18:13:56 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: info: Command Router : Notfying DB Updatetrue May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: 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 08 18:13:56 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: No code May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: 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 08 18:13:56 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: No code May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: 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 08 18:13:56 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: No code May 08 18:13:56 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:56 volumio volumio[1002]: info: ------------------------------ 123ms May 08 18:13:56 volumio volumio[1002]: info: ------------------------------ 121ms May 08 18:13:56 volumio volumio[1002]: info: ------------------------------ 120ms May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: May 08 18:13:56 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: May 08 18:13:56 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: May 08 18:13:56 volumio volumio[1002]: ---------------------------- MPD announces state update: update May 08 18:13:56 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds May 08 18:13:56 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:56 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:56 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:57 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:57 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:57 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:57 volumio volumio[1002]: info: Command Router : Notfying DB Updatefalse May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent May 08 18:13:57 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:13:57 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: 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 08 18:13:57 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: No code May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: 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 08 18:13:57 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: No code May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: 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 08 18:13:57 volumio volumio[1002]: verbose: CURRENT POSITION 0 May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: No code May 08 18:13:57 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:13:57 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:13:57 volumio volumio[1002]: info: ------------------------------ 108ms May 08 18:13:57 volumio volumio[1002]: info: ------------------------------ 107ms May 08 18:13:57 volumio volumio[1002]: info: ------------------------------ 106ms May 08 18:13:57 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioNext May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::next May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::stop May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::play index undefined May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::startPlaybackTimer May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::clearAddPlayTracks USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand stop took 4 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand clear took 1 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac" May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces system playlist update May 08 18:14:01 volumio volumio[1002]: info: Ignoring MPD Status Update May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces system playlist update May 08 18:14:01 volumio volumio[1002]: info: Ignoring MPD Status Update May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand add "USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac" took 4 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand play May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces system playlist update May 08 18:14:01 volumio volumio[1002]: info: Ignoring MPD Status Update May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces system playlist update May 08 18:14:01 volumio volumio[1002]: info: Ignoring MPD Status Update May 08 18:14:01 volumio volumio[1002]: error: Upnp client error: Error: This socket has been ended by the other party May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces state update: player May 08 18:14:01 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 15ms May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 14ms May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand play took 12 milliseconds May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 12ms May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 10ms May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces system playlist update May 08 18:14:01 volumio volumio[1002]: info: Ignoring MPD Status Update May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces state update: player May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces system playlist update May 08 18:14:01 volumio volumio[1002]: info: Ignoring MPD Status Update May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces state update: player May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces state update: player May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 11ms May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 9ms May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你在我心中","artist":"王菲","album":null,"uri":"USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac","trackType":"flac"} May 08 18:14:01 volumio volumio[1002]: verbose: CURRENT POSITION 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 25ms May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces state update: player May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:14:01 volumio volumio[1002]: info: May 08 18:14:01 volumio volumio[1002]: ---------------------------- MPD announces state update: player May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::getState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand status took 13 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 11 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 10 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseState May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"564 Kbps","isStreaming":false,"title":"你在我心中","artist":"王菲","album":null,"uri":"USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac","trackType":"flac"} May 08 18:14:01 volumio volumio[1002]: verbose: CURRENT POSITION 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play May 08 18:14:01 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"564 Kbps","isStreaming":false,"title":"你在我心中","artist":"王菲","album":null,"uri":"USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac","trackType":"flac"} May 08 18:14:01 volumio volumio[1002]: verbose: CURRENT POSITION 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play May 08 18:14:01 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 62ms May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 61ms May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 38 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 38 milliseconds May 08 18:14:01 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 37 milliseconds May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo May 08 18:14:01 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"613 Kbps","isStreaming":false,"title":"你在我心中","artist":"王菲","album":null,"uri":"USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac","trackType":"flac"} May 08 18:14:01 volumio volumio[1002]: verbose: CURRENT POSITION 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play May 08 18:14:01 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"613 Kbps","isStreaming":false,"title":"你在我心中","artist":"王菲","album":null,"uri":"USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac","trackType":"flac"} May 08 18:14:01 volumio volumio[1002]: verbose: CURRENT POSITION 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play May 08 18:14:01 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: ControllerMpd::pushState May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::servicePushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"613 Kbps","isStreaming":false,"title":"你在我心中","artist":"王菲","album":null,"uri":"USB/RAO_32G/百变天后超白金精选-但愿人长久-8987/你在我心中.flac","trackType":"flac"} May 08 18:14:01 volumio volumio[1002]: verbose: CURRENT POSITION 683 May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play May 08 18:14:01 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:01 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:01 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 104ms May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 94ms May 08 18:14:01 volumio volumio[1002]: info: ------------------------------ 94ms May 08 18:14:02 volumio kernel: hwmon hwmon1: Undervoltage detected! May 08 18:14:07 volumio volumio[1002]: info: VolumeController::SetAlsaVolume32 May 08 18:14:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:14:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:07 volumio volumio[1002]: info: VolumeController::SetAlsaVolume41 May 08 18:14:07 volumio volumio[1002]: info: CoreStateMachine::pushState May 08 18:14:07 volumio volumio[1002]: info: CorePlayQueue::getTrack 683 May 08 18:14:07 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 18:14:07 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState May 08 18:14:08 volumio volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:14:08 volumio volumio[1002]: Error: connect ETIMEDOUT 208.77.47.172:80 May 08 18:14:08 volumio volumio[1002]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 08 18:14:08 volumio volumio[1002]: errno: -110, May 08 18:14:08 volumio volumio[1002]: code: 'ETIMEDOUT', May 08 18:14:08 volumio volumio[1002]: syscall: 'connect', May 08 18:14:08 volumio volumio[1002]: address: '208.77.47.172', May 08 18:14:08 volumio volumio[1002]: port: 80 May 08 18:14:08 volumio volumio[1002]: } May 08 18:14:08 volumio volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:14:08 volumio sudo[2003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 18:13 May 08 18:14:08 volumio sudo[2003]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 18:14:08 volumio kernel: hwmon hwmon1: Voltage normalised PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"