-- Logs begin at Mon 2025-02-24 12:02:29 CST, end at Mon 2025-02-24 12:05:45 CST. -- Feb 24 12:04:01 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:05 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::volumioStop Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::stop Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::stPlaybackTimer Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::updateTrackBlock Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrackBlock Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::serviceStop Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::serviceStop Feb 24 12:04:08 volumio volumio[1030]: info: [1740369848617] ControllerWebradio::stop Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 12:04:08 volumio volumio[1030]: info: Feb 24 12:04:08 volumio volumio[1030]: ---------------------------- MPD announces state update: player Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand stop took 12 milliseconds Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:08 volumio volumio[1030]: info: Feb 24 12:04:08 volumio volumio[1030]: ---------------------------- MPD announces state update: player Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:08 volumio volumio[1030]: info: Feb 24 12:04:08 volumio volumio[1030]: ---------------------------- MPD announces state update: player Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:08 volumio volumio[1030]: info: Feb 24 12:04:08 volumio volumio[1030]: ---------------------------- MPD announces state update: player Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 12:04:08 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:08 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:08 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:08 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:08 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:08 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:08 volumio sudo[1864]: pam_unix(sudo:session): session closed for user root Feb 24 12:04:08 volumio volumio[1030]: info: ------------------------------ 54ms Feb 24 12:04:08 volumio volumio[1030]: info: ------------------------------ 54ms Feb 24 12:04:08 volumio volumio[1030]: info: ------------------------------ 52ms Feb 24 12:04:08 volumio volumio[1030]: info: ------------------------------ 51ms Feb 24 12:04:09 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand rescan Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand rescan took 3 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatetrue Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatetrue Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: Feb 24 12:04:10 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 154 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 153 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 95 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 44 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 305ms Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 304ms Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 303ms Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 301ms Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 144 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 121 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 100 milliseconds Feb 24 12:04:10 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 77 milliseconds Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:10 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:10 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:10 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 364ms Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 216ms Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 214ms Feb 24 12:04:10 volumio volumio[1030]: info: ------------------------------ 214ms Feb 24 12:04:13 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand update Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand update took 4 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 8 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: info: Command Router : Notfying DB Updatetrue Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: Feb 24 12:04:16 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:16 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 138 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 136 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 92 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 68 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 46 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 9 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 8 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 8 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 8 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:16 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:16 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:16 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:17 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:17 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 293ms Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 292ms Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 291ms Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 289ms Feb 24 12:04:17 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 155 milliseconds Feb 24 12:04:17 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 133 milliseconds Feb 24 12:04:17 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 24 12:04:17 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 86 milliseconds Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:17 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:17 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:17 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:17 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 351ms Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 219ms Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 217ms Feb 24 12:04:17 volumio volumio[1030]: info: ------------------------------ 217ms Feb 24 12:04:17 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand rescan Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand rescan took 3 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: Feb 24 12:04:18 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:18 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 14 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 13 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 12 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 11 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 11 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 11 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 10 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 10 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 9 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 9 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 8 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 225 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 226 milliseconds Feb 24 12:04:18 volumio volumio[1030]: info: sendMpdCommand status took 224 milliseconds Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:18 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:18 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 227 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 203 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 180 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 156 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 132 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 109 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 50 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:19 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 335ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 334ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 333ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 332ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 331ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 330ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 327ms Feb 24 12:04:19 volumio volumio[1030]: info: ------------------------------ 326ms Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:04:19 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand update Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: Feb 24 12:04:19 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand update took 4 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: Feb 24 12:04:19 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: Feb 24 12:04:19 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: Feb 24 12:04:19 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: Feb 24 12:04:19 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:19 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 54 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 54 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 53 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 9 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:19 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:19 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:19 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 132 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 100 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 77 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 53 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 14 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 14 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 13 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 12 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 12 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 11 milliseconds Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 308ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 306ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 262ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 259ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 258ms Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 89 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 64 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 163ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 162ms Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand rescan Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand rescan took 2 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatetrue Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatetrue Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: Feb 24 12:04:20 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 123 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 122 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 120 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 93 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 68 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 45 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 260ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 260ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 259ms Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 160 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 134 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 88 milliseconds Feb 24 12:04:20 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 66 milliseconds Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:20 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:20 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:20 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 315ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 315ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 197ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 197ms Feb 24 12:04:20 volumio volumio[1030]: info: ------------------------------ 196ms Feb 24 12:04:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:25 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:30 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:04:30 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:33 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:37 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:41 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:41 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:04:41 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:45 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand update Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand update took 3 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 7 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 132 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 131 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 131 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 130 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 270ms Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 269ms Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 268ms Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: Feb 24 12:04:48 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 132 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 109 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 86 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 63 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 23 milliseconds Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand status took 24 milliseconds Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 344ms Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 344ms Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 216ms Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 217ms Feb 24 12:04:48 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 24 12:04:48 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:48 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:48 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:48 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:48 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:48 volumio volumio[1030]: info: ------------------------------ 89ms Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand rescan Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand rescan took 3 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: Feb 24 12:04:49 volumio volumio[1030]: ---------------------------- MPD announces state update: update Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::getState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 132 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 131 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 130 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 94 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 48 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 6 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 5 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 4 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand status took 3 milliseconds Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: info: Command Router : Notfying DB Updatefalse Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseState Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 269ms Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 268ms Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 268ms Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 159 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 132 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 108 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 84 milliseconds Feb 24 12:04:49 volumio volumio[1030]: info: sendMpdCommand playlistinfo took 63 milliseconds Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:49 volumio volumio[1030]: verbose: ControllerMpd::parseTrackInfo Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ControllerMpd::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::servicePushState Feb 24 12:04:49 volumio volumio[1030]: info: CoreStateMachine::pushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::volumioPushState Feb 24 12:04:49 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:49 volumio volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 331ms Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 330ms Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 204ms Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 202ms Feb 24 12:04:49 volumio volumio[1030]: info: ------------------------------ 201ms Feb 24 12:04:49 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:52 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:04:52 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:04:53 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:04:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:01 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:04 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:05:04 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:05:05 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:09 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:09 volumio volumio[1030]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Feb 24 12:05:09 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Feb 24 12:05:09 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Feb 24 12:05:09 volumio volumio[1030]: info: Rebuild Album cache Feb 24 12:05:13 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:15 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:05:15 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:05:17 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:20 volumio volumio[1030]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Feb 24 12:05:20 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Feb 24 12:05:20 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Feb 24 12:05:20 volumio volumio[1030]: info: Rebuild Album cache Feb 24 12:05:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:25 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:26 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:05:26 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:05:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:33 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:37 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Feb 24 12:05:37 volumio volumio[1030]: info: CorePlayQueue::getTrack 0 Feb 24 12:05:37 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:41 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 12:05:45 volumio volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:05:45 volumio volumio[1030]: Error: connect ETIMEDOUT 173.236.182.137:80 Feb 24 12:05:45 volumio volumio[1030]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 24 12:05:45 volumio volumio[1030]: errno: -110, Feb 24 12:05:45 volumio volumio[1030]: code: 'ETIMEDOUT', Feb 24 12:05:45 volumio volumio[1030]: syscall: 'connect', Feb 24 12:05:45 volumio volumio[1030]: address: '173.236.182.137', Feb 24 12:05:45 volumio volumio[1030]: port: 80 Feb 24 12:05:45 volumio volumio[1030]: } Feb 24 12:05:45 volumio volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:05:45 volumio sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 12:04 Feb 24 12:05:45 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"