Mar 26 20:45:02 volumio volumio[1157]: info: UPNP Browser: Returning 0 server(s) after 3s wait Mar 26 20:45:02 volumio volumio[1157]: info: Preload queue cleared Mar 26 20:45:05 volumio volumio[1157]: info: Preload queue cleared Mar 26 20:45:07 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 26 20:45:07 volumio volumio[1157]: info: CURURI: playlists Mar 26 20:45:07 volumio volumio[1157]: info: Listing playlists Mar 26 20:45:08 volumio volumio[1157]: info: Preload queue cleared Mar 26 20:45:13 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 26 20:45:13 volumio volumio[1157]: info: UPNP Browser: No servers found, reinitializing and searching... Mar 26 20:45:16 volumio volumio[1157]: info: UPNP Browser: Returning 0 server(s) after 3s wait Mar 26 20:45:16 volumio volumio[1157]: info: Preload queue cleared Mar 26 20:45:21 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:21 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:25 volumio volumio[1157]: info: CoreCommandRouter::volumioGetState Mar 26 20:45:25 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:27 volumio volumio[1157]: info: Retrieving Cloud Streaming UI Mar 26 20:45:27 volumio volumio[1157]: info: Getting Tidal Cloud Configuration Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:27 volumio volumio[1157]: info: Getting Qobuz Cloud Configuration Mar 26 20:45:27 volumio volumio[1157]: info: Asking plugin for UI Config Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:27 volumio volumio[1157]: info: Getting Spotify Cloud Configuration Mar 26 20:45:27 volumio volumio[1157]: info: Asking plugin for UI Config Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:27 volumio volumio[1157]: info: Saving Spotify Acccount Mar 26 20:45:27 volumio volumio[1157]: info: Got Tidal Cloud Configuration Mar 26 20:45:27 volumio volumio[1157]: info: Got it Mar 26 20:45:27 volumio volumio[1157]: info: Got it Mar 26 20:45:27 volumio volumio[1157]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 26 20:45:27 volumio volumio[1157]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::volumioGetBrowseSources Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:27 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 26 20:45:31 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:33 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 26 20:45:33 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand update Mar 26 20:45:33 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:33 volumio volumio[1157]: info: Mar 26 20:45:33 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:33 volumio volumio[1157]: info: sendMpdCommand update took 9 milliseconds Mar 26 20:45:33 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:33 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:33 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:33 volumio volumio[1157]: info: Mar 26 20:45:33 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:33 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:33 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:33 volumio volumio[1157]: info: sendMpdCommand status took 10 milliseconds Mar 26 20:45:33 volumio volumio[1157]: info: sendMpdCommand status took 8 milliseconds Mar 26 20:45:33 volumio volumio[1157]: info: sendMpdCommand status took 8 milliseconds Mar 26 20:45:33 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:34 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:34 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:34 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:34 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:35 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:35 volumio volumio[1157]: info: Mar 26 20:45:35 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:35 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:35 volumio volumio[1157]: info: sendMpdCommand status took 1928 milliseconds Mar 26 20:45:35 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 518 milliseconds Mar 26 20:45:35 volumio volumio[1157]: info: sendMpdCommand status took 6 milliseconds Mar 26 20:45:35 volumio volumio[1157]: info: sendMpdCommand status took 4 milliseconds Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:35 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:35 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124346,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:35 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:35 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:35 volumio volumio[1157]: info: ------------------------------ 2066ms Mar 26 20:45:35 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 145 milliseconds Mar 26 20:45:35 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 66 milliseconds Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:35 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:35 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124346,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:35 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:35 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:35 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126340,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:35 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:35 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:35 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:35 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:35 volumio volumio[1157]: info: ------------------------------ 2166ms Mar 26 20:45:35 volumio volumio[1157]: info: ------------------------------ 240ms Mar 26 20:45:35 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:36 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:36 volumio volumio[1157]: info: Mar 26 20:45:36 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: Mar 26 20:45:36 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:36 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:36 volumio volumio[1157]: info: Mar 26 20:45:36 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: Mar 26 20:45:36 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:36 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:36 volumio volumio[1157]: info: Mar 26 20:45:36 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: Mar 26 20:45:36 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:36 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:36 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:36 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:37 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 26 20:45:37 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand update Mar 26 20:45:37 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:37 volumio volumio[1157]: info: Mar 26 20:45:37 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:37 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:37 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:37 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:37 volumio volumio[1157]: info: Mar 26 20:45:37 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:37 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:37 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:37 volumio volumio[1157]: info: sendMpdCommand status took 1060 milliseconds Mar 26 20:45:37 volumio volumio[1157]: info: sendMpdCommand status took 1060 milliseconds Mar 26 20:45:37 volumio volumio[1157]: info: sendMpdCommand status took 1060 milliseconds Mar 26 20:45:37 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:39 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:39 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:39 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:39 volumio volumio[1157]: info: Mar 26 20:45:39 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:39 volumio volumio[1157]: info: Mar 26 20:45:39 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:39 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:39 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:39 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:39 volumio volumio[1157]: info: Mar 26 20:45:39 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:39 volumio volumio[1157]: info: Mar 26 20:45:39 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:39 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:39 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:39 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:39 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:39 volumio volumio[1157]: Upnp client error: Error: This socket has been ended by the other party Mar 26 20:45:40 volumio volumio[1157]: info: sendMpdCommand status took 3660 milliseconds Mar 26 20:45:40 volumio volumio[1157]: info: sendMpdCommand status took 3660 milliseconds Mar 26 20:45:40 volumio volumio[1157]: info: sendMpdCommand status took 3664 milliseconds Mar 26 20:45:40 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:41 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:41 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:41 volumio volumio[1157]: info: Mar 26 20:45:41 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4723 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4723 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4723 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand update took 4270 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4158 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4186 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4187 milliseconds Mar 26 20:45:41 volumio volumio[1157]: info: sendMpdCommand status took 4185 milliseconds Mar 26 20:45:41 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:42 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:42 volumio volumio[1157]: info: Mar 26 20:45:42 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:42 volumio volumio[1157]: info: Mar 26 20:45:42 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:42 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:42 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:42 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2729 milliseconds Mar 26 20:45:42 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2728 milliseconds Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:42 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:42 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:42 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128243,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:42 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:42 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:42 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:42 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:42 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:42 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:42 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128243,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:42 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:42 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:42 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:42 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:42 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:42 volumio volumio[1157]: info: ------------------------------ 5729ms Mar 26 20:45:42 volumio volumio[1157]: info: ------------------------------ 5728ms Mar 26 20:45:42 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand update Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:42 volumio volumio[1157]: info: Mar 26 20:45:42 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:42 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:42 volumio volumio[1157]: info: Mar 26 20:45:42 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:42 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:42 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:43 volumio volumio[1157]: info: sendMpdCommand status took 3320 milliseconds Mar 26 20:45:43 volumio volumio[1157]: info: sendMpdCommand status took 3321 milliseconds Mar 26 20:45:43 volumio volumio[1157]: info: sendMpdCommand status took 3321 milliseconds Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:43 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:43 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:43 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:43 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:43 volumio volumio[1157]: info: Mar 26 20:45:43 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:43 volumio volumio[1157]: info: Mar 26 20:45:43 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:43 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:43 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:43 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:43 volumio volumio[1157]: info: Mar 26 20:45:43 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:43 volumio volumio[1157]: info: Mar 26 20:45:43 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:43 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:43 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:43 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand status took 4526 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand status took 4527 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand status took 4527 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 3287 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 3284 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand status took 2740 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand status took 2730 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2195 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2192 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 1959 milliseconds Mar 26 20:45:44 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 1872 milliseconds Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:44 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:44 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:44 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:45 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128449,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:45 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:45 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128449,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:45 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:45 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128541,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:45 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:45 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128541,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:45 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:45 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128747,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:45 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:45 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128747,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:45 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:45 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:45 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:45 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:45 volumio volumio[1157]: info: ------------------------------ 8405ms Mar 26 20:45:45 volumio volumio[1157]: info: ------------------------------ 8405ms Mar 26 20:45:45 volumio volumio[1157]: info: ------------------------------ 8402ms Mar 26 20:45:45 volumio volumio[1157]: info: ------------------------------ 8403ms Mar 26 20:45:45 volumio volumio[1157]: info: ------------------------------ 7818ms Mar 26 20:45:45 volumio volumio[1157]: info: ------------------------------ 7814ms Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:45 volumio volumio[1157]: info: Mar 26 20:45:45 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:45 volumio volumio[1157]: info: MPD Database updated - AlbumList cache refreshed Mar 26 20:45:45 volumio volumio[1157]: info: Mar 26 20:45:45 volumio volumio[1157]: ---------------------------- MPD announces state update: database Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:45 volumio volumio[1157]: info: Mar 26 20:45:45 volumio volumio[1157]: ---------------------------- MPD announces state update: update Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3315 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3311 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3310 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand update took 3206 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3081 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3080 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3080 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand status took 3078 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:45 volumio volumio[1157]: info: ControllerMpd::getState Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand status Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2296 milliseconds Mar 26 20:45:45 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2296 milliseconds Mar 26 20:45:45 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: info: Command Router : Notfying DB Updatetrue Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:46 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:46 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:46 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:46 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131246,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:46 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:46 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:46 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:46 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:46 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:46 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:46 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131246,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:46 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:46 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:46 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:46 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:46 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:46 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:46 volumio volumio[1157]: info: ------------------------------ 6793ms Mar 26 20:45:46 volumio volumio[1157]: info: ------------------------------ 6793ms Mar 26 20:45:47 volumio volumio[1157]: info: sendMpdCommand status took 3502 milliseconds Mar 26 20:45:47 volumio volumio[1157]: info: sendMpdCommand status took 3502 milliseconds Mar 26 20:45:47 volumio volumio[1157]: info: sendMpdCommand status took 3501 milliseconds Mar 26 20:45:47 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:47 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:47 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:47 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:47 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:47 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:47 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:47 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand status took 4411 milliseconds Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand status took 4411 milliseconds Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand status took 4410 milliseconds Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 3164 milliseconds Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 3165 milliseconds Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2899 milliseconds Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:48 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:48 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131361,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:48 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:48 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:48 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131361,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:48 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:48 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:48 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133447,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:48 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:48 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:48 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:48 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:48 volumio volumio[1157]: info: ------------------------------ 8722ms Mar 26 20:45:48 volumio volumio[1157]: info: ------------------------------ 8723ms Mar 26 20:45:48 volumio volumio[1157]: info: ------------------------------ 6933ms Mar 26 20:45:48 volumio volumio[1157]: info: sendMpdCommand status took 2733 milliseconds Mar 26 20:45:48 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:48 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:48 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:48 volumio kernel: mmc1: Controller never released inhibit bit(s). Mar 26 20:45:48 volumio kernel: brcmfmac: brcmf_sdio_readframes: read 1536 bytes from channel 2 failed: -5 Mar 26 20:45:48 volumio kernel: brcmfmac: brcmf_sdio_rxfail: abort command, terminate frame Mar 26 20:45:48 volumio kernel: brcmfmac: brcmf_sdio_hdparse: HW header length too long Mar 26 20:45:48 volumio kernel: brcmfmac: brcmf_sdio_rxfail: terminate frame Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand status took 3443 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand status took 3439 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand status took 3437 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand status took 3437 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2961 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2960 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2872 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2802 milliseconds Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:49 volumio volumio[1157]: info: Command Router : Notfying DB Updatefalse Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::Close All Modals sent Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseState Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133951,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133951,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133951,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133951,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ------------------------------ 7219ms Mar 26 20:45:49 volumio volumio[1157]: info: ------------------------------ 7218ms Mar 26 20:45:49 volumio volumio[1157]: info: ------------------------------ 6981ms Mar 26 20:45:49 volumio volumio[1157]: info: ------------------------------ 6980ms Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2199 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 2200 milliseconds Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":134960,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":134960,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ------------------------------ 6237ms Mar 26 20:45:49 volumio volumio[1157]: info: ------------------------------ 6237ms Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 1416 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 1416 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 204 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 205 milliseconds Mar 26 20:45:49 volumio volumio[1157]: info: sendMpdCommand playlistinfo took 204 milliseconds Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: verbose: ControllerMpd::parseTrackInfo Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135143,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135143,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137551,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137757,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: ControllerMpd::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::servicePushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137757,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"그것만이 내 세상","artist":"들국화","album":null,"uri":"INTERNAL/Pop/Ballade/들국화 - 그것만이 내 세상.mp3","trackType":"mp3"} Mar 26 20:45:49 volumio volumio[1157]: verbose: CURRENT POSITION 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState stateService play Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::syncState currentStatus play Mar 26 20:45:49 volumio volumio[1157]: info: Received an update from plugin. extracting info from payload Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:49 volumio volumio[1157]: info: CoreStateMachine::pushState Mar 26 20:45:49 volumio volumio[1157]: info: CorePlayQueue::getTrack 262 Mar 26 20:45:49 volumio volumio[1157]: info: CoreCommandRouter::volumioPushState Mar 26 20:45:50 volumio volumio[1157]: info: ------------------------------ 6414ms Mar 26 20:45:50 volumio volumio[1157]: info: ------------------------------ 6413ms Mar 26 20:45:50 volumio volumio[1157]: info: ------------------------------ 4171ms Mar 26 20:45:50 volumio volumio[1157]: info: ------------------------------ 4166ms Mar 26 20:45:50 volumio volumio[1157]: info: ------------------------------ 4167ms Mar 26 20:45:51 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:55 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:59 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:45:59 volumio volumio[1157]: info: Disabling MyMusic plugin upnp Mar 26 20:45:59 volumio sudo[3279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 26 20:45:59 volumio sudo[3279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 20:45:59 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Mar 26 20:46:03 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 26 20:46:06 volumio volumio[1157]: info: Enabling MyMusic plugin upnp Mar 26 20:46:06 volumio volumio[1157]: info: Enabling plugin upnp Mar 26 20:46:07 volumio volumio[1157]: info: Loading plugin "upnp"... Mar 26 20:46:07 volumio volumio[1157]: info: [1774525567343] Starting Upmpd Daemon Mar 26 20:46:07 volumio volumio[1157]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 20:46:07 volumio volumio[1157]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:46:07 volumio volumio[1157]: Error: listen EADDRINUSE: address already in use :::6599 Mar 26 20:46:07 volumio volumio[1157]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Mar 26 20:46:07 volumio volumio[1157]: at listenInCluster (node:net:1920:12) Mar 26 20:46:07 volumio volumio[1157]: at Server.listen (node:net:2008:7) Mar 26 20:46:07 volumio volumio[1157]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 26 20:46:07 volumio volumio[1157]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 26 20:46:07 volumio volumio[1157]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 26 20:46:07 volumio volumio[1157]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 26 20:46:07 volumio volumio[1157]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Mar 26 20:46:07 volumio volumio[1157]: code: 'EADDRINUSE', Mar 26 20:46:07 volumio volumio[1157]: errno: -98, Mar 26 20:46:07 volumio volumio[1157]: syscall: 'listen', Mar 26 20:46:07 volumio volumio[1157]: address: '::', Mar 26 20:46:07 volumio volumio[1157]: port: 6599 Mar 26 20:46:07 volumio volumio[1157]: } Mar 26 20:46:07 volumio volumio[1157]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 20:46:08 volumio sudo[3322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 20:45' Mar 26 20:46:08 volumio sudo[3322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"