Sep 01 01:51:14 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:14 volumio volumio[79223]: info: CURURI: genres:// Sep 01 01:51:14 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:33 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Sep 01 01:51:35 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Sep 01 01:51:35 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Sep 01 01:51:36 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:36 volumio volumio[79223]: info: CURURI: artists:// Sep 01 01:51:36 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:36 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:36 volumio volumio[79223]: info: CURURI: albums:// Sep 01 01:51:36 volumio volumio[79223]: info: listAlbums - loading Albums from cache Sep 01 01:51:37 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:38 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:38 volumio volumio[79223]: info: CURURI: artists:// Sep 01 01:51:38 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:39 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:39 volumio volumio[79223]: info: CURURI: music-library Sep 01 01:51:39 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:40 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:40 volumio volumio[79223]: info: CURURI: music-library/INTERNAL Sep 01 01:51:40 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:40 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 01:51:40 volumio volumio[79223]: info: CURURI: music-library/INTERNAL/音乐 Sep 01 01:51:40 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:51:52 volumio volumio[79223]: Searching plugin music_service/mpd Sep 01 01:51:52 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 01 01:51:52 volumio volumio[79223]: info: All search sources collected, pushing search results Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:56 volumio volumio[79223]: info: Retrieving Cloud Streaming UI Sep 01 01:51:56 volumio volumio[79223]: info: Getting Tidal Cloud Configuration Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:56 volumio volumio[79223]: info: Getting Qobuz Cloud Configuration Sep 01 01:51:56 volumio volumio[79223]: info: Asking plugin for UI Config Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:56 volumio volumio[79223]: info: Getting Spotify Cloud Configuration Sep 01 01:51:56 volumio volumio[79223]: info: Asking plugin for UI Config Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:56 volumio volumio[79223]: info: Saving Spotify Acccount Sep 01 01:51:56 volumio volumio[79223]: info: Got Tidal Cloud Configuration Sep 01 01:51:56 volumio volumio[79223]: info: Got it Sep 01 01:51:56 volumio volumio[79223]: info: Got it Sep 01 01:51:56 volumio volumio[79223]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 01:51:56 volumio volumio[79223]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 01:51:56 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand update Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: Sep 01 01:51:58 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand update took 4 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: Sep 01 01:51:58 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: Sep 01 01:51:58 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 8 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 6 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 6 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 5 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 5 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:51:58 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: No code Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:51:58 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: No code Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:51:58 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: No code Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: ------------------------------ 133ms Sep 01 01:51:58 volumio volumio[79223]: info: ------------------------------ 131ms Sep 01 01:51:58 volumio volumio[79223]: info: ------------------------------ 129ms Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: Sep 01 01:51:58 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: Sep 01 01:51:58 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: Sep 01 01:51:58 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 26 milliseconds Sep 01 01:51:58 volumio volumio[79223]: info: sendMpdCommand status took 26 milliseconds Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:51:58 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: 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 01:51:58 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: No code Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: 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 01:51:58 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: No code Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: 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 01:51:58 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: No code Sep 01 01:51:58 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:51:58 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:51:58 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:51:58 volumio volumio[79223]: info: ------------------------------ 168ms Sep 01 01:51:58 volumio volumio[79223]: info: ------------------------------ 168ms Sep 01 01:51:58 volumio volumio[79223]: info: ------------------------------ 167ms Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 4 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 139ms Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 138ms Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 137ms Sep 01 01:52:00 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: Sep 01 01:52:00 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 24 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 23 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 23 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 22 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 22 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 22 milliseconds Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: 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 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: 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 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: 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 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: 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 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 147ms Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 146ms Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 146ms Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 150ms Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 159 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 158 milliseconds Sep 01 01:52:00 volumio volumio[79223]: info: sendMpdCommand status took 158 milliseconds Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: 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 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: 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 01:52:00 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: No code Sep 01 01:52:00 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:00 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:00 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 227ms Sep 01 01:52:00 volumio volumio[79223]: info: ------------------------------ 227ms Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:01 volumio volumio[79223]: info: Sep 01 01:52:01 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand rescan took 3 milliseconds Sep 01 01:52:01 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:01 volumio volumio[79223]: info: Sep 01 01:52:01 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:01 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:01 volumio volumio[79223]: info: Sep 01 01:52:01 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:01 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:01 volumio volumio[79223]: info: sendMpdCommand status took 1 milliseconds Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:01 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:01 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:01 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:01 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:01 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:01 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: No code Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:01 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: No code Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:01 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:01 volumio volumio[79223]: info: No code Sep 01 01:52:01 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:01 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:01 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 117ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 117ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 116ms Sep 01 01:52:02 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 28 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 88ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 89ms Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 86 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 86 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 86 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 85 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 85 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 85 milliseconds Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 207ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 208ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 207ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 208ms Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand rescan Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand rescan took 2 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 2 milliseconds Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatetrue Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 115ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 115ms Sep 01 01:52:02 volumio volumio[79223]: info: ------------------------------ 114ms Sep 01 01:52:02 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: MPD Database updated - AlbumList cache refreshed Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: database Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: Sep 01 01:52:02 volumio volumio[79223]: ---------------------------- MPD announces state update: update Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 27 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 26 milliseconds Sep 01 01:52:02 volumio volumio[79223]: info: sendMpdCommand status took 26 milliseconds Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: 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 01:52:02 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:02 volumio volumio[79223]: info: No code Sep 01 01:52:02 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:02 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:02 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: ------------------------------ 103ms Sep 01 01:52:03 volumio volumio[79223]: info: ------------------------------ 103ms Sep 01 01:52:03 volumio volumio[79223]: info: sendMpdCommand status took 100 milliseconds Sep 01 01:52:03 volumio volumio[79223]: info: sendMpdCommand status took 100 milliseconds Sep 01 01:52:03 volumio volumio[79223]: info: sendMpdCommand status took 100 milliseconds Sep 01 01:52:03 volumio volumio[79223]: info: sendMpdCommand status took 99 milliseconds Sep 01 01:52:03 volumio volumio[79223]: info: sendMpdCommand status took 99 milliseconds Sep 01 01:52:03 volumio volumio[79223]: info: sendMpdCommand status took 100 milliseconds Sep 01 01:52:03 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:03 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:03 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:03 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:03 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:03 volumio volumio[79223]: info: Command Router : Notfying DB Updatefalse Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::Close All Modals sent Sep 01 01:52:03 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:03 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:03 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: 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 01:52:03 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: No code Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: 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 01:52:03 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: No code Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: 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 01:52:03 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: No code Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: 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 01:52:03 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState stateService stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: No code Sep 01 01:52:03 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:03 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:03 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:03 volumio volumio[79223]: info: ------------------------------ 221ms Sep 01 01:52:03 volumio volumio[79223]: info: ------------------------------ 221ms Sep 01 01:52:03 volumio volumio[79223]: info: ------------------------------ 221ms Sep 01 01:52:03 volumio volumio[79223]: info: ------------------------------ 222ms Sep 01 01:52:10 volumio volumio[79223]: Searching plugin music_service/mpd Sep 01 01:52:10 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 01 01:52:10 volumio volumio[79223]: info: All search sources collected, pushing search results Sep 01 01:52:13 volumio volumio[79223]: Searching plugin music_service/mpd Sep 01 01:52:13 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 01 01:52:13 volumio volumio[79223]: info: All search sources collected, pushing search results Sep 01 01:52:13 volumio volumio[79223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AD%89%E4%BB%80%E4%B9%88%E5%90%9B/%E6%98%A5%E5%BA%AD%E9%9B%AA/b1633927-37ba-4329-874e-1882b72e197b.jpg' Sep 01 01:52:16 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::ClearQueue Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::stop Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::clearPlayQueue Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::saveQueue Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::addQueueItems Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::addQueueItems Sep 01 01:52:16 volumio volumio[79223]: info: Preload queue cleared Sep 01 01:52:16 volumio volumio[79223]: info: Adding Item to queue: albums://%E7%AD%89%E4%BB%80%E4%B9%88%E5%90%9B/%E6%98%A5%E5%BA%AD%E9%9B%AA Sep 01 01:52:16 volumio volumio[79223]: info: Exploding uri albums://%E7%AD%89%E4%BB%80%E4%B9%88%E5%90%9B/%E6%98%A5%E5%BA%AD%E9%9B%AA in service mpd Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushQueue Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::saveQueue Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::updateTrackBlock Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrackBlock Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPlay Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::play index 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::stop Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::play index undefined Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::startPlaybackTimer Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/音乐/1 - 春庭雪.flac Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand stop took 1 milliseconds Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces system playlist update Sep 01 01:52:16 volumio volumio[79223]: info: Ignoring MPD Status Update Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand clear took 2 milliseconds Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/音乐/1 - 春庭雪.flac" Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces system playlist update Sep 01 01:52:16 volumio volumio[79223]: info: Ignoring MPD Status Update Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces system playlist update Sep 01 01:52:16 volumio volumio[79223]: info: Ignoring MPD Status Update Sep 01 01:52:16 volumio volumio[79223]: error: updateQueue error: null Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces system playlist update Sep 01 01:52:16 volumio volumio[79223]: info: Ignoring MPD Status Update Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 4ms Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand add "INTERNAL/音乐/1 - 春庭雪.flac" took 3 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 3ms Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 2ms Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand play Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces system playlist update Sep 01 01:52:16 volumio volumio[79223]: info: Ignoring MPD Status Update Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces system playlist update Sep 01 01:52:16 volumio volumio[79223]: info: Ignoring MPD Status Update Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 5ms Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand play took 4 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces state update: player Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 5ms Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 5ms Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces state update: player Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces state update: player Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces state update: player Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand status took 18 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand status took 19 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand status took 20 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand status took 3 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"149 Kbps","isStreaming":false,"title":"春庭雪","artist":"等什么君","album":"春庭雪","uri":"INTERNAL/音乐/1 - 春庭雪.flac","trackType":"flac"} Sep 01 01:52:16 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState stateService play Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus stop Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 25ms Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces state update: player Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:16 volumio volumio[79223]: info: Sep 01 01:52:16 volumio volumio[79223]: ---------------------------- MPD announces state update: player Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::getState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand status Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":461,"duration":241,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"153 Kbps","isStreaming":false,"title":"春庭雪","artist":"等什么君","album":"春庭雪","uri":"INTERNAL/音乐/1 - 春庭雪.flac","trackType":"flac"} Sep 01 01:52:16 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState stateService play Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus play Sep 01 01:52:16 volumio volumio[79223]: info: Received an update from plugin. extracting info from payload Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":482,"duration":241,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"136 Kbps","isStreaming":false,"title":"春庭雪","artist":"等什么君","album":"春庭雪","uri":"INTERNAL/音乐/1 - 春庭雪.flac","trackType":"flac"} Sep 01 01:52:16 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState stateService play Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus play Sep 01 01:52:16 volumio volumio[79223]: info: Received an update from plugin. extracting info from payload Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":241,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"春庭雪","artist":"等什么君","album":"春庭雪","uri":"INTERNAL/音乐/1 - 春庭雪.flac","trackType":"flac"} Sep 01 01:52:16 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState stateService play Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus play Sep 01 01:52:16 volumio volumio[79223]: info: Received an update from plugin. extracting info from payload Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 70ms Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 70ms Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 54ms Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand status took 50 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand status took 49 milliseconds Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseState Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 01 01:52:16 volumio volumio[79223]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:52:16 volumio volumio[79223]: verbose: ControllerMpd::parseTrackInfo Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":241,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"春庭雪","artist":"等什么君","album":"春庭雪","uri":"INTERNAL/音乐/1 - 春庭雪.flac","trackType":"flac"} Sep 01 01:52:16 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState stateService play Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus play Sep 01 01:52:16 volumio volumio[79223]: info: Received an update from plugin. extracting info from payload Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: ControllerMpd::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::servicePushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":241,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"春庭雪","artist":"等什么君","album":"春庭雪","uri":"INTERNAL/音乐/1 - 春庭雪.flac","trackType":"flac"} Sep 01 01:52:16 volumio volumio[79223]: verbose: CURRENT POSITION 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState stateService play Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::syncState currentStatus play Sep 01 01:52:16 volumio volumio[79223]: info: Received an update from plugin. extracting info from payload Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: CoreStateMachine::pushState Sep 01 01:52:16 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:16 volumio volumio[79223]: info: CoreCommandRouter::volumioPushState Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 105ms Sep 01 01:52:16 volumio volumio[79223]: info: ------------------------------ 104ms Sep 01 01:52:16 volumio volumio[79223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AD%89%E4%BB%80%E4%B9%88%E5%90%9B/%E6%98%A5%E5%BA%AD%E9%9B%AA/b1633927-37ba-4329-874e-1882b72e197b.jpg' Sep 01 01:52:16 volumio volumio[79223]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AD%89%E4%BB%80%E4%B9%88%E5%90%9B/%E6%98%A5%E5%BA%AD%E9%9B%AA/b1633927-37ba-4329-874e-1882b72e197b.jpg' Sep 01 01:52:21 volumio sudo[82515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:52:21 volumio sudo[82515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:52:21 volumio sudo[82517]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:52:21 volumio sudo[82515]: pam_unix(sudo:session): session closed for user root Sep 01 01:52:21 volumio sudo[82517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:52:21 volumio sudo[82517]: pam_unix(sudo:session): session closed for user root Sep 01 01:52:21 volumio volumio[79223]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 QuarkPC/7.1.5.968 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 01:52:21 volumio sudo[82523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:52:21 volumio sudo[82523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:52:21 volumio sudo[82523]: pam_unix(sudo:session): session closed for user root Sep 01 01:52:21 volumio sudo[82525]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:52:21 volumio sudo[82525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:52:21 volumio sudo[82525]: pam_unix(sudo:session): session closed for user root Sep 01 01:52:21 volumio volumio[79223]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 QuarkPC/7.1.5.968 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::volumioGetState Sep 01 01:52:21 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:52:21 volumio volumio[79223]: info: Listing playlists Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 01 01:52:21 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 01:52:24 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 01:52:24 volumio volumio[79223]: info: Received Get System Info Sep 01 01:52:24 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 01:52:24 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 01:52:24 volumio volumio[79223]: info: Discovery: Getting this device information Sep 01 01:52:24 volumio volumio[79223]: info: CoreCommandRouter::volumioGetState Sep 01 01:52:24 volumio volumio[79223]: info: CorePlayQueue::getTrack 0 Sep 01 01:52:24 volumio volumio[79223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 01:52:47 volumio volumio[79223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:52:47 volumio volumio[79223]: Error: connect ETIMEDOUT 173.252.105.21:443 Sep 01 01:52:47 volumio volumio[79223]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 01:52:47 volumio volumio[79223]: errno: -110, Sep 01 01:52:47 volumio volumio[79223]: code: 'ETIMEDOUT', Sep 01 01:52:47 volumio volumio[79223]: syscall: 'connect', Sep 01 01:52:47 volumio volumio[79223]: address: '173.252.105.21', Sep 01 01:52:47 volumio volumio[79223]: port: 443 Sep 01 01:52:47 volumio volumio[79223]: } Sep 01 01:52:47 volumio volumio[79223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:52:47 volumio sudo[82661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:51' Sep 01 01:52:47 volumio sudo[82661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"