-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Wed 2026-02-18 16:04:43 MSK. -- Feb 18 16:03:04 volumio volumio[1059]: info: Preload queue cleared Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::ClearQueue Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::stop Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::stPlaybackTimer Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::updateTrackBlock Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrackBlock Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::serviceStop Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::serviceStop Feb 18 16:03:04 volumio volumio[1059]: info: [1771419784201] ControllerWebradio::stop Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::clearPlayQueue Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::saveQueue Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushQueue Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::addQueueItems Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::addQueueItems Feb 18 16:03:04 volumio volumio[1059]: info: Preload queue cleared Feb 18 16:03:04 volumio volumio[1059]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580092 Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushQueue Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::saveQueue Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::updateTrackBlock Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrackBlock Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPlay Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::play index 0 Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::stop Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::play index undefined Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::startPlaybackTimer Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: [1771419784228] ControllerWebradio::clearAddPlayTrack Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand stop took 31 milliseconds Feb 18 16:03:04 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand stop took 5 milliseconds Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:04 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:04 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:04 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand status took 17 milliseconds Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand clear took 16 milliseconds Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand status took 9 milliseconds Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand status took 5 milliseconds Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580092" Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:04 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 18 16:03:04 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:04 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:04 volumio volumio[1059]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 50ms Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 47ms Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:04 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:04 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:04 volumio volumio[1059]: error: updateQueue error: null Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 51ms Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 45 milliseconds Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:04 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 70ms Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:04 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580092" took 149 milliseconds Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 112ms Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 110ms Feb 18 16:03:04 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 16:03:04 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand play Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 6ms Feb 18 16:03:04 volumio volumio[1059]: info: sendMpdCommand play took 2 milliseconds Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:04 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:04 volumio volumio[1059]: info: Feb 18 16:03:04 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:04 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 6ms Feb 18 16:03:04 volumio volumio[1059]: info: ------------------------------ 4ms Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 23 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 22 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 21 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 3 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: 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":"radio320.mp3","artist":"(#1 - 20/200) Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: 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":"radio320.mp3","artist":"(#1 - 20/200) Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: 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":"radio320.mp3","artist":"(#1 - 20/200) Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 44ms Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 74ms Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 74ms Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 52 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 3 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 3 milliseconds Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: 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":"radio320.mp3","artist":"(#1 - 20/200) Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 87ms Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: 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":"radio320.mp3","artist":"(#1 - 20/200) Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: 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":"radio320.mp3","artist":"(#1 - 20/200) Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 84ms Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 83ms Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:10 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:10 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:10 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:10 volumio volumio[1059]: info: Feb 18 16:03:10 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 7ms Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 6 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 5ms Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 5ms Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand status took 5 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bruce Springsteen - Dancing In The Dark 1984","artist":"Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bruce Springsteen - Dancing In The Dark 1984","artist":"Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 57ms Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 56ms Feb 18 16:03:10 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 49 milliseconds Feb 18 16:03:10 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:10 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:10 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:10 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bruce Springsteen - Dancing In The Dark 1984","artist":"Radio Caroline 319 8319","album":null,"uri":"https://server-10.rcgoldserver.nl:8319/radio320.mp3","trackType":"mp3"} Feb 18 16:03:10 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:10 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:10 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:10 volumio volumio[1059]: info: ------------------------------ 77ms Feb 18 16:03:26 volumio volumio[1059]: info: Preload queue cleared Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::ClearQueue Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::stop Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::stPlaybackTimer Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::updateTrackBlock Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrackBlock Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::serviceStop Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::serviceStop Feb 18 16:03:26 volumio volumio[1059]: info: [1771419806658] ControllerWebradio::stop Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::clearPlayQueue Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::saveQueue Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushQueue Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::addQueueItems Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::addQueueItems Feb 18 16:03:26 volumio volumio[1059]: info: Preload queue cleared Feb 18 16:03:26 volumio volumio[1059]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960 Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushQueue Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::saveQueue Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::updateTrackBlock Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrackBlock Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPlay Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::play index 0 Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::stop Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::play index undefined Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::startPlaybackTimer Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: [1771419806684] ControllerWebradio::clearAddPlayTrack Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand stop took 29 milliseconds Feb 18 16:03:26 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand stop took 5 milliseconds Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:26 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:26 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:26 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand status took 5 milliseconds Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand clear took 5 milliseconds Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand status took 3 milliseconds Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand status took 2 milliseconds Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:26 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 18 16:03:26 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:26 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:26 volumio volumio[1059]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 32ms Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 32ms Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:26 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:26 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:26 volumio volumio[1059]: error: updateQueue error: null Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 33ms Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:26 volumio volumio[1059]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 40ms Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" took 145 milliseconds Feb 18 16:03:26 volumio volumio[1059]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 16:03:26 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand play Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:26 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 120ms Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 119ms Feb 18 16:03:26 volumio volumio[1059]: info: sendMpdCommand play took 2 milliseconds Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:26 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:26 volumio volumio[1059]: info: Feb 18 16:03:26 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:26 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 6ms Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 4ms Feb 18 16:03:26 volumio volumio[1059]: info: ------------------------------ 3ms Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 23 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 21 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 23 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 3 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: 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":"chillout","artist":"(#1 - 900/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: 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":"chillout","artist":"(#1 - 900/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 47ms Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 64ms Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 43 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 43 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 5 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 3 milliseconds Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: 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":"chillout","artist":"(#1 - 900/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: 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":"chillout","artist":"(#1 - 900/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 129ms Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 110ms Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:28 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 68 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 69 milliseconds Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: 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":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: 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":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 126ms Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 126ms Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:28 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:28 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:28 volumio volumio[1059]: info: Feb 18 16:03:28 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 60ms Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 59 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 6ms Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 4ms Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 18 16:03:28 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:28 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:28 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:28 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:28 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:28 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 143ms Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 90ms Feb 18 16:03:28 volumio volumio[1059]: info: ------------------------------ 88ms Feb 18 16:03:29 volumio volumio[1059]: info: Feb 18 16:03:29 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:29 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:29 volumio volumio[1059]: info: Feb 18 16:03:29 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:29 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:29 volumio volumio[1059]: info: Feb 18 16:03:29 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:29 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:29 volumio volumio[1059]: info: Feb 18 16:03:29 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:29 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:29 volumio volumio[1059]: info: Feb 18 16:03:29 volumio volumio[1059]: ---------------------------- MPD announces system playlist update Feb 18 16:03:29 volumio volumio[1059]: info: Ignoring MPD Status Update Feb 18 16:03:29 volumio volumio[1059]: info: Feb 18 16:03:29 volumio volumio[1059]: ---------------------------- MPD announces state update: player Feb 18 16:03:29 volumio volumio[1059]: info: ControllerMpd::getState Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Feb 18 16:03:29 volumio volumio[1059]: info: ------------------------------ 9ms Feb 18 16:03:29 volumio volumio[1059]: info: sendMpdCommand status took 7 milliseconds Feb 18 16:03:29 volumio volumio[1059]: info: ------------------------------ 6ms Feb 18 16:03:29 volumio volumio[1059]: info: sendMpdCommand status took 6 milliseconds Feb 18 16:03:29 volumio volumio[1059]: info: ------------------------------ 5ms Feb 18 16:03:29 volumio volumio[1059]: info: sendMpdCommand status took 4 milliseconds Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::parseState Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 16:03:29 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:29 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 16:03:29 volumio volumio[1059]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:29 volumio volumio[1059]: verbose: ControllerMpd::parseTrackInfo Feb 18 16:03:29 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:29 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2279,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:29 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:29 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:29 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:29 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2279,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:29 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:29 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:29 volumio volumio[1059]: info: ControllerMpd::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::servicePushState Feb 18 16:03:29 volumio volumio[1059]: info: CorePlayQueue::getTrack 0 Feb 18 16:03:29 volumio volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2279,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Markus Hakala - Curacao (Original Mix)","artist":"CHILLOUT ANTENNE","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Feb 18 16:03:29 volumio volumio[1059]: verbose: CURRENT POSITION 0 Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::syncState stateService play Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::syncState currentStatus play Feb 18 16:03:29 volumio volumio[1059]: info: Received an update from plugin. extracting info from payload Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreStateMachine::pushState Feb 18 16:03:29 volumio volumio[1059]: info: CoreCommandRouter::volumioPushState Feb 18 16:03:29 volumio volumio[1059]: info: ------------------------------ 88ms Feb 18 16:03:29 volumio volumio[1059]: info: ------------------------------ 86ms Feb 18 16:03:29 volumio volumio[1059]: info: ------------------------------ 85ms Feb 18 16:04:43 volumio volumio[1059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 16:04:43 volumio volumio[1059]: Error: connect ETIMEDOUT 23.73.2.84:443 Feb 18 16:04:43 volumio volumio[1059]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 18 16:04:43 volumio volumio[1059]: errno: -110, Feb 18 16:04:43 volumio volumio[1059]: code: 'ETIMEDOUT', Feb 18 16:04:43 volumio volumio[1059]: syscall: 'connect', Feb 18 16:04:43 volumio volumio[1059]: address: '23.73.2.84', Feb 18 16:04:43 volumio volumio[1059]: port: 443 Feb 18 16:04:43 volumio volumio[1059]: } Feb 18 16:04:43 volumio volumio[1059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 16:04:43 volumio sudo[2507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-18 16:03 Feb 18 16:04:43 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"