-- Logs begin at Mon 2025-09-01 15:54:29 CST, end at Mon 2025-09-01 15:59:04 CST. -- Sep 01 15:58:02 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:02 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:13 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:13 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:14 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:14 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 15:58:16 volumio volumio[512]: info: Retrieving Cloud Streaming UI Sep 01 15:58:16 volumio volumio[512]: info: Getting Tidal Cloud Configuration Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 15:58:16 volumio volumio[512]: info: Getting Qobuz Cloud Configuration Sep 01 15:58:16 volumio volumio[512]: info: Asking plugin for UI Config Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 15:58:16 volumio volumio[512]: info: Getting Spotify Cloud Configuration Sep 01 15:58:16 volumio volumio[512]: info: Asking plugin for UI Config Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 15:58:16 volumio volumio[512]: info: Saving Spotify Acccount Sep 01 15:58:16 volumio volumio[512]: info: Got Tidal Cloud Configuration Sep 01 15:58:16 volumio volumio[512]: info: Got it Sep 01 15:58:16 volumio volumio[512]: info: Got it Sep 01 15:58:16 volumio volumio[512]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 15:58:16 volumio volumio[512]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:16 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand update Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: info: Sep 01 15:58:18 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand update took 6 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: info: Sep 01 15:58:18 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: info: Sep 01 15:58:18 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: info: Sep 01 15:58:18 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: info: Sep 01 15:58:18 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 16 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 13 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 12 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 10 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 11 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 9 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 8 milliseconds Sep 01 15:58:18 volumio volumio[512]: info: sendMpdCommand status took 8 milliseconds Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:18 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: 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} Sep 01 15:58:18 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: No code Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: 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} Sep 01 15:58:18 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: No code Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: 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} Sep 01 15:58:18 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: No code Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: 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} Sep 01 15:58:18 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:18 volumio volumio[512]: info: No code Sep 01 15:58:18 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:18 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:18 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 369ms Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 367ms Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 367ms Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 365ms Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:19 volumio volumio[512]: info: Sep 01 15:58:19 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:19 volumio volumio[512]: info: Sep 01 15:58:19 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:19 volumio volumio[512]: info: Sep 01 15:58:19 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 382 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 382 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 19 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 18 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 17 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 17 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 16 milliseconds Sep 01 15:58:19 volumio volumio[512]: info: sendMpdCommand status took 15 milliseconds Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:19 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: 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} Sep 01 15:58:19 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: No code Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: 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} Sep 01 15:58:19 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: No code Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: 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} Sep 01 15:58:19 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: No code Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: 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} Sep 01 15:58:19 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: No code Sep 01 15:58:19 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:19 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:19 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 700ms Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 337ms Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 335ms Sep 01 15:58:19 volumio volumio[512]: info: ------------------------------ 332ms Sep 01 15:58:20 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:24 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:24 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:24 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:28 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:32 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: Sep 01 15:58:34 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand rescan took 6 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: Sep 01 15:58:34 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:34 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 4 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 2 milliseconds Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:34 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:34 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:34 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:34 volumio volumio[512]: 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} Sep 01 15:58:34 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:34 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:34 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:34 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:34 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:34 volumio volumio[512]: info: No code Sep 01 15:58:34 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:34 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:34 volumio volumio[512]: info: ------------------------------ 130ms Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: Sep 01 15:58:34 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:34 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: Sep 01 15:58:34 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:34 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: Sep 01 15:58:34 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:34 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 138 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 138 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 11 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 10 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 10 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 9 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 8 milliseconds Sep 01 15:58:34 volumio volumio[512]: info: sendMpdCommand status took 8 milliseconds Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:34 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:34 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:34 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 422ms Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 296ms Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 295ms Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 294ms Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:35 volumio volumio[512]: info: Sep 01 15:58:35 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:35 volumio volumio[512]: info: Sep 01 15:58:35 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:35 volumio volumio[512]: info: Sep 01 15:58:35 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:35 volumio volumio[512]: info: sendMpdCommand status took 103 milliseconds Sep 01 15:58:35 volumio volumio[512]: info: sendMpdCommand status took 101 milliseconds Sep 01 15:58:35 volumio volumio[512]: info: sendMpdCommand status took 94 milliseconds Sep 01 15:58:35 volumio volumio[512]: info: sendMpdCommand status took 93 milliseconds Sep 01 15:58:35 volumio volumio[512]: info: sendMpdCommand status took 92 milliseconds Sep 01 15:58:35 volumio volumio[512]: info: sendMpdCommand status took 91 milliseconds Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:35 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: 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} Sep 01 15:58:35 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: No code Sep 01 15:58:35 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 322ms Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 314ms Sep 01 15:58:35 volumio volumio[512]: info: ------------------------------ 312ms Sep 01 15:58:35 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:35 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:36 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand update Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand update took 4 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 11 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 10 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 9 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 8 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 7 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 6 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 6 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 5 milliseconds Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 323ms Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 321ms Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 320ms Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 319ms Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: Sep 01 15:58:37 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 338 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 336 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 15 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 14 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 11 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 10 milliseconds Sep 01 15:58:37 volumio volumio[512]: info: sendMpdCommand status took 9 milliseconds Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:37 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: 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} Sep 01 15:58:37 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: No code Sep 01 15:58:37 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:37 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:37 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 606ms Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 283ms Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 280ms Sep 01 15:58:37 volumio volumio[512]: info: ------------------------------ 278ms Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand update Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand update took 4 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 3 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 3 milliseconds Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 75ms Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 80 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 79 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 6 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 5 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 4 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 3 milliseconds Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 372ms Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 299ms Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 298ms Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 298ms Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 66 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 66 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 65 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 64 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 64 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 64 milliseconds Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 277ms Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 277ms Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 276ms Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand update Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand update took 4 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 3 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 2 milliseconds Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: 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} Sep 01 15:58:38 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: No code Sep 01 15:58:38 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:38 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:38 volumio volumio[512]: info: ------------------------------ 82ms Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: Sep 01 15:58:38 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:38 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 88 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 7 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 6 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 5 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 4 milliseconds Sep 01 15:58:38 volumio volumio[512]: info: sendMpdCommand status took 3 milliseconds Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:38 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:38 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:38 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 351ms Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 270ms Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 269ms Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 267ms Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:39 volumio volumio[512]: info: Sep 01 15:58:39 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:39 volumio volumio[512]: info: Sep 01 15:58:39 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:39 volumio volumio[512]: info: Sep 01 15:58:39 volumio volumio[512]: ---------------------------- MPD announces state update: update Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::getState Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::sendMpdCommand status Sep 01 15:58:39 volumio volumio[512]: info: sendMpdCommand status took 69 milliseconds Sep 01 15:58:39 volumio volumio[512]: info: sendMpdCommand status took 67 milliseconds Sep 01 15:58:39 volumio volumio[512]: info: sendMpdCommand status took 66 milliseconds Sep 01 15:58:39 volumio volumio[512]: info: sendMpdCommand status took 66 milliseconds Sep 01 15:58:39 volumio volumio[512]: info: sendMpdCommand status took 66 milliseconds Sep 01 15:58:39 volumio volumio[512]: info: sendMpdCommand status took 65 milliseconds Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: info: Command Router : Notfying DB Updatefalse Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::Close All Modals sent Sep 01 15:58:39 volumio volumio[512]: verbose: ControllerMpd::parseState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ControllerMpd::pushState Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::servicePushState Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: 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} Sep 01 15:58:39 volumio volumio[512]: verbose: CURRENT POSITION 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState stateService stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::syncState currentStatus stop Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: No code Sep 01 15:58:39 volumio volumio[512]: info: CoreStateMachine::pushState Sep 01 15:58:39 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:39 volumio volumio[512]: info: CoreCommandRouter::volumioPushState Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 292ms Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 290ms Sep 01 15:58:39 volumio volumio[512]: info: ------------------------------ 290ms Sep 01 15:58:40 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:43 volumio kernel: ............. Sep 01 15:58:43 volumio kernel: usb 2-3: USB disconnect, device number 2 Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] tag#14 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] tag#14 CDB: Test Unit Ready 00 00 00 00 00 00 Sep 01 15:58:43 volumio kernel: ready Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Read Capacity(16) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Sense not available. Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Read Capacity(10) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Sense not available. Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] 0 512-byte logical blocks: (0 B/0 B) Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] 0-byte physical blocks Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Write Protect is off Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Mode Sense: 00 00 00 00 Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Asking for cache data failed Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Assuming drive cache: write through Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes not a multiple of physical block size (0 bytes) Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of physical block size (0 bytes) Sep 01 15:58:43 volumio kernel: sd 2:0:0:0: [sdb] Attached SCSI disk Sep 01 15:58:44 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:46 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:46 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:58:48 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:52 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:56 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:58:57 volumio volumio[512]: info: CoreCommandRouter::volumioGetState Sep 01 15:58:57 volumio volumio[512]: info: CorePlayQueue::getTrack 1 Sep 01 15:59:00 volumio volumio[512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 15:59:04 volumio volumio[512]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 15:59:04 volumio volumio[512]: Error: connect ETIMEDOUT 173.252.88.133:443 Sep 01 15:59:04 volumio volumio[512]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 01 15:59:04 volumio volumio[512]: errno: -110, Sep 01 15:59:04 volumio volumio[512]: code: 'ETIMEDOUT', Sep 01 15:59:04 volumio volumio[512]: syscall: 'connect', Sep 01 15:59:04 volumio volumio[512]: address: '173.252.88.133', Sep 01 15:59:04 volumio volumio[512]: port: 443 Sep 01 15:59:04 volumio volumio[512]: } Sep 01 15:59:04 volumio volumio[512]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 15:59:04 volumio sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 15:58 Sep 01 15:59:04 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"