Dec 19 17:28:01 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand update Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: info: Dec 19 17:28:02 volumio volumio[1346]: ---------------------------- MPD announces state update: update Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand update took 2 milliseconds Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: info: Dec 19 17:28:02 volumio volumio[1346]: ---------------------------- MPD announces state update: update Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand status took 3 milliseconds Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:02 volumio volumio[1346]: info: Command Router : Notfying DB Updatefalse Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::Close All Modals sent Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 19 17:28:02 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::syncState stateService stop Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: No code Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: ------------------------------ 799ms Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: info: Dec 19 17:28:02 volumio volumio[1346]: ---------------------------- MPD announces state update: update Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand status took 800 milliseconds Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand status took 800 milliseconds Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:28:02 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:02 volumio volumio[1346]: info: Command Router : Notfying DB Updatefalse Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::Close All Modals sent Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:02 volumio volumio[1346]: info: Command Router : Notfying DB Updatefalse Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::Close All Modals sent Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 19 17:28:02 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::syncState stateService stop Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: No code Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 19 17:28:02 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::syncState stateService stop Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: No code Dec 19 17:28:02 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:02 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:02 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:02 volumio volumio[1346]: info: ------------------------------ 855ms Dec 19 17:28:02 volumio volumio[1346]: info: ------------------------------ 58ms Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:02 volumio volumio[1346]: info: Dec 19 17:28:02 volumio volumio[1346]: ---------------------------- MPD announces state update: update Dec 19 17:28:02 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:02 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:03 volumio volumio[1346]: info: sendMpdCommand status took 22 milliseconds Dec 19 17:28:03 volumio volumio[1346]: info: sendMpdCommand status took 22 milliseconds Dec 19 17:28:03 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:03 volumio volumio[1346]: info: Command Router : Notfying DB Updatefalse Dec 19 17:28:03 volumio volumio[1346]: info: CoreCommandRouter::Close All Modals sent Dec 19 17:28:03 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:03 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:03 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:03 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:03 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:03 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:03 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:03 volumio volumio[1346]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 19 17:28:03 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:03 volumio volumio[1346]: info: CoreStateMachine::syncState stateService stop Dec 19 17:28:03 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop Dec 19 17:28:03 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:03 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:03 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:03 volumio volumio[1346]: info: No code Dec 19 17:28:03 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:03 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:03 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:03 volumio volumio[1346]: info: ------------------------------ 53ms Dec 19 17:28:04 volumio volumio[1346]: info: CoreCommandRouter::volumioPlay Dec 19 17:28:04 volumio volumio[1346]: info: CoreStateMachine::play index undefined Dec 19 17:28:04 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:28:04 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:11 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:15 volumio volumio[1346]: Searching all installed plugins Dec 19 17:28:15 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:28:15 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: , search Dec 19 17:28:15 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 19 17:28:15 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 19 17:28:15 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 19 17:28:15 volumio volumio[1346]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 19 17:28:15 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 19 17:28:16 volumio volumio[1346]: info: All search sources collected, pushing search results Dec 19 17:28:17 volumio volumio[1346]: Searching all installed plugins Dec 19 17:28:17 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:28:17 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: , search Dec 19 17:28:17 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 19 17:28:17 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 19 17:28:17 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 19 17:28:17 volumio volumio[1346]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 19 17:28:17 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 19 17:28:17 volumio volumio[1346]: info: All search sources collected, pushing search results Dec 19 17:28:22 volumio volumio[1346]: Searching all installed plugins Dec 19 17:28:22 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:28:22 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: , search Dec 19 17:28:22 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 19 17:28:22 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 19 17:28:22 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 19 17:28:22 volumio volumio[1346]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 19 17:28:22 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 19 17:28:22 volumio volumio[1346]: info: SHOUTCAST-kyselyssä tapahtui virhe. Dec 19 17:28:22 volumio volumio[1346]: info: All search sources collected, pushing search results Dec 19 17:28:27 volumio volumio[1346]: Searching all installed plugins Dec 19 17:28:27 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:28:27 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: , search Dec 19 17:28:27 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 19 17:28:27 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 19 17:28:27 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 19 17:28:27 volumio volumio[1346]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 19 17:28:27 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 19 17:28:28 volumio volumio[1346]: info: All search sources collected, pushing search results Dec 19 17:28:38 volumio volumio[1346]: Searching all installed plugins Dec 19 17:28:38 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:28:38 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: , search Dec 19 17:28:38 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 19 17:28:38 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 19 17:28:38 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 19 17:28:38 volumio volumio[1346]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 19 17:28:38 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 19 17:28:40 volumio volumio[1346]: info: All search sources collected, pushing search results Dec 19 17:28:44 volumio volumio[1346]: Searching all installed plugins Dec 19 17:28:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:28:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: , search Dec 19 17:28:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 19 17:28:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Dec 19 17:28:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Dec 19 17:28:44 volumio volumio[1346]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Dec 19 17:28:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 19 17:28:44 volumio volumio[1346]: info: All search sources collected, pushing search results Dec 19 17:28:50 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 19 17:28:50 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:51 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 19 17:28:51 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:53 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 19 17:28:53 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:55 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 19 17:28:55 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:58 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:58 volumio volumio[1346]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::ClearQueue Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::stop Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::clearPlayQueue Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::saveQueue Dec 19 17:28:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushQueue Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::addQueueItems Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::addQueueItems Dec 19 17:28:58 volumio volumio[1346]: info: Preload queue cleared Dec 19 17:28:58 volumio volumio[1346]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Dec 19 17:28:58 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 19 17:28:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushQueue Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::saveQueue Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::updateTrackBlock Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::getTrackBlock Dec 19 17:28:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPlay Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::play index 0 Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::stop Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::play index undefined Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:58 volumio volumio[1346]: info: CoreStateMachine::startPlaybackTimer Dec 19 17:28:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:58 volumio volumio[1346]: info: [1766158138791] ControllerWebradio::clearAddPlayTrack Dec 19 17:28:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand stop Dec 19 17:28:58 volumio volumio[1346]: info: sendMpdCommand stop took 3 milliseconds Dec 19 17:28:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand clear Dec 19 17:28:58 volumio volumio[1346]: info: Dec 19 17:28:58 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:28:58 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:28:58 volumio volumio[1346]: info: sendMpdCommand clear took 1 milliseconds Dec 19 17:28:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Dec 19 17:28:58 volumio volumio[1346]: info: Dec 19 17:28:58 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:28:58 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:28:58 volumio volumio[1346]: error: updateQueue error: null Dec 19 17:28:58 volumio volumio[1346]: info: ------------------------------ 3ms Dec 19 17:28:59 volumio volumio[1346]: info: Dec 19 17:28:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:28:59 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 264 milliseconds Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand play Dec 19 17:28:59 volumio volumio[1346]: info: ------------------------------ 265ms Dec 19 17:28:59 volumio volumio[1346]: info: ------------------------------ 3ms Dec 19 17:28:59 volumio volumio[1346]: info: Dec 19 17:28:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:28:59 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand play took 4 milliseconds Dec 19 17:28:59 volumio volumio[1346]: info: ------------------------------ 2ms Dec 19 17:28:59 volumio volumio[1346]: info: Dec 19 17:28:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:59 volumio volumio[1346]: info: Dec 19 17:28:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:28:59 volumio volumio[1346]: info: Dec 19 17:28:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:59 volumio volumio[1346]: info: Dec 19 17:28:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand status took 4 milliseconds Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5821/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:28:59 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop Dec 19 17:28:59 volumio volumio[1346]: info: ------------------------------ 10ms Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand status took 5 milliseconds Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5821/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:28:59 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:28:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5821/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:28:59 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:28:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:59 volumio volumio[1346]: info: ------------------------------ 35ms Dec 19 17:28:59 volumio volumio[1346]: info: ------------------------------ 33ms Dec 19 17:28:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 19 17:28:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:28:59 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5821/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:28:59 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:28:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:28:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 45ms Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:29:00 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:29:00 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:29:00 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 4ms Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand status took 3 milliseconds Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 3ms Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces system playlist update Dec 19 17:29:00 volumio volumio[1346]: info: Ignoring MPD Status Update Dec 19 17:29:00 volumio volumio[1346]: info: Dec 19 17:29:00 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 6ms Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand status took 4 milliseconds Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 3ms Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:29:00 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:00 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:29:00 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:29:00 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:29:00 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:00 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:29:00 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:29:00 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 31ms Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 31ms Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 19 17:29:00 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:29:00 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:29:00 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:00 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:29:00 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:29:00 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:29:00 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:00 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Dec 19 17:29:00 volumio volumio[1346]: verbose: CURRENT POSITION 0 Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play Dec 19 17:29:00 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:00 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 55ms Dec 19 17:29:00 volumio volumio[1346]: info: ------------------------------ 51ms Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::volumioStop Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::stop Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::stPlaybackTimer Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::updateTrackBlock Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrackBlock Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::serviceStop Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::serviceStop Dec 19 17:29:06 volumio volumio[1346]: info: [1766158146238] ControllerWebradio::stop Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand stop Dec 19 17:29:06 volumio volumio[1346]: info: Dec 19 17:29:06 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:29:06 volumio volumio[1346]: info: sendMpdCommand stop took 15 milliseconds Dec 19 17:29:06 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:29:06 volumio volumio[1346]: info: Dec 19 17:29:06 volumio volumio[1346]: ---------------------------- MPD announces state update: player Dec 19 17:29:06 volumio volumio[1346]: info: ControllerMpd::getState Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status Dec 19 17:29:06 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds Dec 19 17:29:06 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::parseState Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 17:29:06 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:29:06 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:06 volumio volumio[1346]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 19 17:29:06 volumio volumio[1346]: info: ------------------------------ 14ms Dec 19 17:29:06 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 19 17:29:06 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo Dec 19 17:29:06 volumio volumio[1346]: info: ControllerMpd::pushState Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::servicePushState Dec 19 17:29:06 volumio volumio[1346]: info: CoreStateMachine::pushState Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:06 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState Dec 19 17:29:06 volumio volumio[1346]: info: CorePlayQueue::getTrack 0 Dec 19 17:29:06 volumio volumio[1346]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 19 17:29:06 volumio volumio[1346]: info: ------------------------------ 20ms Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 17:29:12 volumio volumio[1346]: info: Retrieving Cloud Streaming UI Dec 19 17:29:12 volumio volumio[1346]: info: Getting Tidal Cloud Configuration Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 17:29:12 volumio volumio[1346]: info: Getting Qobuz Cloud Configuration Dec 19 17:29:12 volumio volumio[1346]: info: Asking plugin for UI Config Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 17:29:12 volumio volumio[1346]: info: Getting Spotify Cloud Configuration Dec 19 17:29:12 volumio volumio[1346]: info: Asking plugin for UI Config Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 17:29:12 volumio volumio[1346]: info: Saving Spotify Acccount Dec 19 17:29:12 volumio volumio[1346]: info: Got Tidal Cloud Configuration Dec 19 17:29:12 volumio volumio[1346]: info: Got it Dec 19 17:29:12 volumio volumio[1346]: info: Got it Dec 19 17:29:12 volumio volumio[1346]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 17:29:12 volumio volumio[1346]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 19 17:29:12 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 19 17:29:15 volumio volumio[1346]: info: Disabling MyMusic plugin upnp Dec 19 17:29:15 volumio sudo[2985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 19 17:29:15 volumio sudo[2985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 17:29:15 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Dec 19 17:29:16 volumio volumio[1346]: info: Enabling MyMusic plugin upnp Dec 19 17:29:16 volumio volumio[1346]: info: Enabling plugin upnp Dec 19 17:29:16 volumio volumio[1346]: info: Loading plugin "upnp"... Dec 19 17:29:16 volumio volumio[1346]: info: [1766158156191] Starting Upmpd Daemon Dec 19 17:29:16 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:29:16 volumio volumio[1346]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:29:16 volumio volumio[1346]: Error: listen EADDRINUSE: address already in use :::6599 Dec 19 17:29:16 volumio volumio[1346]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Dec 19 17:29:16 volumio volumio[1346]: at listenInCluster (node:net:1920:12) Dec 19 17:29:16 volumio volumio[1346]: at Server.listen (node:net:2008:7) Dec 19 17:29:16 volumio volumio[1346]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 19 17:29:16 volumio volumio[1346]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 19 17:29:16 volumio volumio[1346]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 19 17:29:16 volumio volumio[1346]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 19 17:29:16 volumio volumio[1346]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 19 17:29:16 volumio volumio[1346]: code: 'EADDRINUSE', Dec 19 17:29:16 volumio volumio[1346]: errno: -98, Dec 19 17:29:16 volumio volumio[1346]: syscall: 'listen', Dec 19 17:29:16 volumio volumio[1346]: address: '::', Dec 19 17:29:16 volumio volumio[1346]: port: 6599 Dec 19 17:29:16 volumio volumio[1346]: } Dec 19 17:29:16 volumio volumio[1346]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:29:16 volumio sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 17:28' Dec 19 17:29:16 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"