-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2025-06-20 09:33:00 CST. -- Jun 20 09:32:01 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:01 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:01 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Jun 20 09:32:01 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:01 volumio volumio[1144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791 Jun 20 09:32:01 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 20 09:32:01 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:01 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::play index 0 Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::play index undefined Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Jun 20 09:32:01 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:01 volumio volumio[1144]: info: [1750383121393] ControllerWebradio::clearAddPlayTrack Jun 20 09:32:01 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:01 volumio volumio[1144]: info: sendMpdCommand stop took 4 milliseconds Jun 20 09:32:01 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 09:32:01 volumio volumio[1144]: info: Jun 20 09:32:01 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:01 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:01 volumio volumio[1144]: info: sendMpdCommand clear took 19 milliseconds Jun 20 09:32:01 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791" Jun 20 09:32:01 volumio volumio[1144]: info: Jun 20 09:32:01 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:01 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:01 volumio volumio[1144]: info: Jun 20 09:32:01 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:01 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:01 volumio volumio[1144]: error: updateQueue error: null Jun 20 09:32:01 volumio volumio[1144]: info: Jun 20 09:32:01 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:01 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:01 volumio volumio[1144]: info: ------------------------------ 226ms Jun 20 09:32:01 volumio volumio[1144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791" took 225 milliseconds Jun 20 09:32:01 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 09:32:01 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Jun 20 09:32:01 volumio volumio[1144]: info: ------------------------------ 227ms Jun 20 09:32:01 volumio volumio[1144]: info: ------------------------------ 226ms Jun 20 09:32:01 volumio volumio[1144]: info: Jun 20 09:32:01 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:01 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:01 volumio volumio[1144]: info: Jun 20 09:32:01 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:01 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:01 volumio volumio[1144]: info: ------------------------------ 11ms Jun 20 09:32:01 volumio volumio[1144]: info: sendMpdCommand play took 11 milliseconds Jun 20 09:32:01 volumio volumio[1144]: info: ------------------------------ 6ms Jun 20 09:32:01 volumio volumio[1144]: info: ------------------------------ 4ms Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 9 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac","artist":"(#1 - 40/5000) MGT Sertanejo Universit?rio","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac","artist":"(#1 - 40/5000) MGT Sertanejo Universit?rio","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 31ms Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 34ms Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac","artist":"(#1 - 40/5000) MGT Sertanejo Universit?rio","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aac","artist":"(#1 - 40/5000) MGT Sertanejo Universit?rio","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 90ms Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 87ms Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:02 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 79 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 74 milliseconds Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gabi Martins - Só Dá Você Na Minha Vida","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 102ms Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:02 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:02 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:02 volumio volumio[1144]: info: Jun 20 09:32:02 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 33ms Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 32 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 12ms Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 11 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 9ms Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gabi Martins - Só Dá Você Na Minha Vida","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 137ms Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 20 09:32:02 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"71 Kbps","isStreaming":false,"title":"Gabi Martins - Só Dá Você Na Minha Vida","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"71 Kbps","isStreaming":false,"title":"Gabi Martins - Só Dá Você Na Minha Vida","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:02 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:02 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"71 Kbps","isStreaming":false,"title":"Gabi Martins - Só Dá Você Na Minha Vida","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:02 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:02 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:02 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 114ms Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 92ms Jun 20 09:32:02 volumio volumio[1144]: info: ------------------------------ 89ms Jun 20 09:32:04 volumio volumio[1144]: info: Jun 20 09:32:04 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:04 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:04 volumio volumio[1144]: info: Jun 20 09:32:04 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:04 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:04 volumio volumio[1144]: info: Jun 20 09:32:04 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:04 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:04 volumio volumio[1144]: info: Jun 20 09:32:04 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:04 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:04 volumio volumio[1144]: info: Jun 20 09:32:04 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:04 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:04 volumio volumio[1144]: info: Jun 20 09:32:04 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:04 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:04 volumio volumio[1144]: info: ------------------------------ 11ms Jun 20 09:32:04 volumio volumio[1144]: info: sendMpdCommand status took 10 milliseconds Jun 20 09:32:04 volumio volumio[1144]: info: ------------------------------ 8ms Jun 20 09:32:04 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Jun 20 09:32:04 volumio volumio[1144]: info: ------------------------------ 6ms Jun 20 09:32:04 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:04 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 09:32:04 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:04 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:04 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:04 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:04 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2777,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"71 Kbps","isStreaming":false,"title":"Duany - Casa Comigo","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:04 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:04 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:04 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:04 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:04 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2777,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"71 Kbps","isStreaming":false,"title":"Duany - Casa Comigo","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:04 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:04 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:04 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:04 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:04 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2777,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"71 Kbps","isStreaming":false,"title":"Duany - Casa Comigo","artist":"MGT Sertanejo Universitário","album":null,"uri":"http://192.95.21.215:8020/aac","trackType":""} Jun 20 09:32:04 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:04 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:04 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:04 volumio volumio[1144]: info: ------------------------------ 57ms Jun 20 09:32:04 volumio volumio[1144]: info: ------------------------------ 55ms Jun 20 09:32:04 volumio volumio[1144]: info: ------------------------------ 54ms Jun 20 09:32:09 volumio volumio[1144]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 20 09:32:09 volumio volumio[1144]: info: Retrying Login Due to Network Error Jun 20 09:32:13 volumio volumio[1144]: info: Saving local item radio-favourites Jun 20 09:32:13 volumio volumio[1144]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1776791","favourite":false} Jun 20 09:32:14 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::serviceStop Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Jun 20 09:32:14 volumio volumio[1144]: info: [1750383134295] ControllerWebradio::stop Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Jun 20 09:32:14 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:14 volumio volumio[1144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611259 Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::play index 0 Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::play index undefined Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: [1750383134306] ControllerWebradio::clearAddPlayTrack Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand stop took 14 milliseconds Jun 20 09:32:14 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand stop took 3 milliseconds Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:14 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:14 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:14 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand clear took 4 milliseconds Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand status took 3 milliseconds Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand status took 2 milliseconds Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611259" Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:14 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 20 09:32:14 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:14 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:14 volumio volumio[1144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 12ms Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 11ms Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:14 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:14 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:14 volumio volumio[1144]: error: updateQueue error: null Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 13ms Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:14 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 19ms Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611259" took 220 milliseconds Jun 20 09:32:14 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 09:32:14 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:14 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 216ms Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 215ms Jun 20 09:32:14 volumio volumio[1144]: info: sendMpdCommand play took 4 milliseconds Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:14 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:14 volumio volumio[1144]: info: Jun 20 09:32:14 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:14 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 8ms Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 6ms Jun 20 09:32:14 volumio volumio[1144]: info: ------------------------------ 5ms Jun 20 09:32:16 volumio volumio[1144]: info: Jun 20 09:32:16 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:16 volumio volumio[1144]: info: Jun 20 09:32:16 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:16 volumio volumio[1144]: info: Jun 20 09:32:16 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:16 volumio volumio[1144]: info: Jun 20 09:32:16 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:16 volumio volumio[1144]: info: Jun 20 09:32:16 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand status took 11 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:16 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:16 volumio volumio[1144]: 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":"","artist":"(#1 - 46/600) A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:16 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:16 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:16 volumio volumio[1144]: 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":"","artist":"(#1 - 46/600) A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:16 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:16 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:16 volumio volumio[1144]: info: ------------------------------ 37ms Jun 20 09:32:16 volumio volumio[1144]: info: ------------------------------ 40ms Jun 20 09:32:16 volumio volumio[1144]: info: Jun 20 09:32:16 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand status took 33 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:16 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:16 volumio volumio[1144]: 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":"","artist":"(#1 - 46/600) A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:16 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:16 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:16 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:16 volumio volumio[1144]: 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":"","artist":"(#1 - 46/600) A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:16 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:16 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:16 volumio volumio[1144]: info: ------------------------------ 67ms Jun 20 09:32:16 volumio volumio[1144]: info: ------------------------------ 67ms Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 20 09:32:16 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:16 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:16 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:16 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:16 volumio volumio[1144]: 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":"","artist":"(#1 - 46/600) A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:16 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:16 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:16 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:16 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:17 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:17 volumio volumio[1144]: 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":"","artist":"(#1 - 46/600) A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:17 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:17 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 104ms Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 76ms Jun 20 09:32:17 volumio volumio[1144]: info: Jun 20 09:32:17 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:17 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:17 volumio volumio[1144]: info: Jun 20 09:32:17 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:17 volumio volumio[1144]: info: Jun 20 09:32:17 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:17 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:17 volumio volumio[1144]: info: Jun 20 09:32:17 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:17 volumio volumio[1144]: info: Jun 20 09:32:17 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:17 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:17 volumio volumio[1144]: info: Jun 20 09:32:17 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 10ms Jun 20 09:32:17 volumio volumio[1144]: info: sendMpdCommand status took 9 milliseconds Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 8ms Jun 20 09:32:17 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 6ms Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:17 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Jun 20 09:32:17 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 09:32:17 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:17 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:17 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Joe Bonamassa - The Heart That Never Waits","artist":"A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:17 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:17 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:17 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:17 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Joe Bonamassa - The Heart That Never Waits","artist":"A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:17 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:17 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 38ms Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 37ms Jun 20 09:32:17 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 20 09:32:17 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:17 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:17 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:17 volumio volumio[1144]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Joe Bonamassa - The Heart That Never Waits","artist":"A MISSISSIPPI BLUES","album":null,"uri":"https://cast1.torontocast.com:4450/","trackType":"com:4450/"} Jun 20 09:32:17 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:17 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:17 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:17 volumio volumio[1144]: info: ------------------------------ 47ms Jun 20 09:32:19 volumio volumio[1144]: info: MyVolumio login type: Token Jun 20 09:32:23 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::stPlaybackTimer Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::serviceStop Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::serviceStop Jun 20 09:32:23 volumio volumio[1144]: info: [1750383143756] ControllerWebradio::stop Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Jun 20 09:32:23 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:23 volumio volumio[1144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1758210 Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::play index 0 Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::play index undefined Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: [1750383143770] ControllerWebradio::clearAddPlayTrack Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand stop took 28 milliseconds Jun 20 09:32:23 volumio volumio[1144]: info: Jun 20 09:32:23 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:23 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand stop took 16 milliseconds Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 09:32:23 volumio volumio[1144]: info: Jun 20 09:32:23 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:23 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:23 volumio volumio[1144]: info: Jun 20 09:32:23 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:23 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:23 volumio volumio[1144]: info: Jun 20 09:32:23 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:23 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand clear took 6 milliseconds Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand status took 5 milliseconds Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand status took 3 milliseconds Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1758210" Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:23 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 20 09:32:23 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:23 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:23 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:23 volumio volumio[1144]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 20 09:32:23 volumio volumio[1144]: info: ------------------------------ 20ms Jun 20 09:32:23 volumio volumio[1144]: info: ------------------------------ 19ms Jun 20 09:32:23 volumio volumio[1144]: info: Jun 20 09:32:23 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:23 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:23 volumio volumio[1144]: info: Jun 20 09:32:23 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:23 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:23 volumio volumio[1144]: error: updateQueue error: null Jun 20 09:32:23 volumio volumio[1144]: info: ------------------------------ 23ms Jun 20 09:32:23 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 20 09:32:23 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:23 volumio volumio[1144]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 20 09:32:23 volumio volumio[1144]: info: ------------------------------ 32ms Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1758210" took 217 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Jun 20 09:32:24 volumio volumio[1144]: info: Jun 20 09:32:24 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:24 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 206ms Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 206ms Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand play took 4 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: Jun 20 09:32:24 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:24 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:24 volumio volumio[1144]: info: Jun 20 09:32:24 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:24 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 10ms Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 7ms Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 5ms Jun 20 09:32:24 volumio volumio[1144]: info: Jun 20 09:32:24 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:24 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:24 volumio volumio[1144]: info: Jun 20 09:32:24 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:24 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:24 volumio volumio[1144]: info: Jun 20 09:32:24 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:24 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand status took 9 milliseconds Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand status took 14 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand clearerror took 10 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand clearerror took 9 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:24 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:24 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 58/999) Soul Cafe Radio","album":null,"uri":"http://51.161.117.116:5354/stream","trackType":""} Jun 20 09:32:24 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: No code Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:24 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 58/999) Soul Cafe Radio","album":null,"uri":"http://51.161.117.116:5354/stream","trackType":""} Jun 20 09:32:24 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: No code Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 48ms Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 48ms Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand clearerror took 47 milliseconds Jun 20 09:32:24 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 46 milliseconds Jun 20 09:32:24 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:24 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:24 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 58/999) Soul Cafe Radio","album":null,"uri":"http://51.161.117.116:5354/stream","trackType":""} Jun 20 09:32:24 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: No code Jun 20 09:32:24 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:24 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:24 volumio volumio[1144]: info: ------------------------------ 87ms Jun 20 09:32:31 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:31 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:31 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Jun 20 09:32:31 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:31 volumio volumio[1144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99587670 Jun 20 09:32:31 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 20 09:32:31 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:31 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::play index 0 Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::play index undefined Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Jun 20 09:32:31 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:31 volumio volumio[1144]: info: [1750383151760] ControllerWebradio::clearAddPlayTrack Jun 20 09:32:31 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:31 volumio volumio[1144]: info: sendMpdCommand stop took 4 milliseconds Jun 20 09:32:31 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 09:32:31 volumio volumio[1144]: info: sendMpdCommand clear took 0 milliseconds Jun 20 09:32:31 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99587670" Jun 20 09:32:31 volumio volumio[1144]: info: Jun 20 09:32:31 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:31 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:31 volumio volumio[1144]: info: Jun 20 09:32:31 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:31 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:31 volumio volumio[1144]: info: Jun 20 09:32:31 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:31 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:31 volumio volumio[1144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99587670" took 215 milliseconds Jun 20 09:32:31 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 09:32:31 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Jun 20 09:32:31 volumio volumio[1144]: info: Jun 20 09:32:31 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:31 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:31 volumio volumio[1144]: info: ------------------------------ 219ms Jun 20 09:32:31 volumio volumio[1144]: info: ------------------------------ 218ms Jun 20 09:32:31 volumio volumio[1144]: info: ------------------------------ 216ms Jun 20 09:32:31 volumio volumio[1144]: info: Jun 20 09:32:31 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:31 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:31 volumio volumio[1144]: info: Jun 20 09:32:31 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:31 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:31 volumio volumio[1144]: info: sendMpdCommand play took 10 milliseconds Jun 20 09:32:31 volumio volumio[1144]: info: ------------------------------ 9ms Jun 20 09:32:31 volumio volumio[1144]: info: ------------------------------ 6ms Jun 20 09:32:31 volumio volumio[1144]: info: ------------------------------ 5ms Jun 20 09:32:34 volumio volumio[1144]: info: Jun 20 09:32:34 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:34 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:34 volumio volumio[1144]: info: Jun 20 09:32:34 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:34 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:34 volumio volumio[1144]: info: Jun 20 09:32:34 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:34 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand status took 9 milliseconds Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand status took 8 milliseconds Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand status took 20 milliseconds Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand clearerror took 16 milliseconds Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand clearerror took 15 milliseconds Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:34 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:34 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 27/100) CHRISTIANISMOS","album":null,"uri":"https://cast5.magicstreams.gr:8019/stream","trackType":""} Jun 20 09:32:34 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: No code Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:34 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 27/100) CHRISTIANISMOS","album":null,"uri":"https://cast5.magicstreams.gr:8019/stream","trackType":""} Jun 20 09:32:34 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: No code Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: ------------------------------ 54ms Jun 20 09:32:34 volumio volumio[1144]: info: ------------------------------ 54ms Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand clearerror took 44 milliseconds Jun 20 09:32:34 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 42 milliseconds Jun 20 09:32:34 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:34 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:34 volumio volumio[1144]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 27/100) CHRISTIANISMOS","album":null,"uri":"https://cast5.magicstreams.gr:8019/stream","trackType":""} Jun 20 09:32:34 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::syncState stateService stop Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: No code Jun 20 09:32:34 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:34 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:34 volumio volumio[1144]: info: ------------------------------ 82ms Jun 20 09:32:35 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:35 volumio volumio[1144]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::ClearQueue Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::clearPlayQueue Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:35 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::addQueueItems Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::addQueueItems Jun 20 09:32:35 volumio volumio[1144]: info: Preload queue cleared Jun 20 09:32:35 volumio volumio[1144]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955 Jun 20 09:32:35 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 20 09:32:35 volumio volumio[1144]: info: CoreCommandRouter::volumioPushQueue Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::saveQueue Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::updateTrackBlock Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::getTrackBlock Jun 20 09:32:35 volumio volumio[1144]: info: CoreCommandRouter::volumioPlay Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::play index 0 Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::stop Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::play index undefined Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::startPlaybackTimer Jun 20 09:32:35 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:35 volumio volumio[1144]: info: [1750383155715] ControllerWebradio::clearAddPlayTrack Jun 20 09:32:35 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 09:32:35 volumio volumio[1144]: info: sendMpdCommand stop took 3 milliseconds Jun 20 09:32:35 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 09:32:35 volumio volumio[1144]: info: sendMpdCommand clear took 1 milliseconds Jun 20 09:32:35 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955" Jun 20 09:32:35 volumio volumio[1144]: info: Jun 20 09:32:35 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:35 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:35 volumio volumio[1144]: info: Jun 20 09:32:35 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:35 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:35 volumio volumio[1144]: info: Jun 20 09:32:35 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:35 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:35 volumio volumio[1144]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955" took 222 milliseconds Jun 20 09:32:35 volumio volumio[1144]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 09:32:35 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand play Jun 20 09:32:35 volumio volumio[1144]: info: Jun 20 09:32:35 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:35 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:35 volumio volumio[1144]: info: ------------------------------ 225ms Jun 20 09:32:35 volumio volumio[1144]: info: ------------------------------ 223ms Jun 20 09:32:35 volumio volumio[1144]: info: ------------------------------ 222ms Jun 20 09:32:35 volumio volumio[1144]: info: sendMpdCommand play took 3 milliseconds Jun 20 09:32:35 volumio volumio[1144]: info: Jun 20 09:32:35 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:35 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:35 volumio volumio[1144]: info: Jun 20 09:32:35 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:35 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:35 volumio volumio[1144]: info: ------------------------------ 9ms Jun 20 09:32:35 volumio volumio[1144]: info: ------------------------------ 6ms Jun 20 09:32:35 volumio volumio[1144]: info: ------------------------------ 4ms Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 7 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 6 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 4 milliseconds Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Radio-Mozart","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus stop Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Radio-Mozart","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Radio-Mozart","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 32ms Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 38ms Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 38ms Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:38 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:38 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Radio-Mozart","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 53ms Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 50ms Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 48ms Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:38 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:38 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 44ms Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 43 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 43ms Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 42 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 24ms Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 23 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 22ms Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 22 milliseconds Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:38 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces system playlist update Jun 20 09:32:38 volumio volumio[1144]: info: Ignoring MPD Status Update Jun 20 09:32:38 volumio volumio[1144]: info: Jun 20 09:32:38 volumio volumio[1144]: ---------------------------- MPD announces state update: player Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::getState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand status Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 24ms Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 24 milliseconds Jun 20 09:32:38 volumio volumio[1144]: info: ------------------------------ 23ms Jun 20 09:32:38 volumio volumio[1144]: info: sendMpdCommand status took 22 milliseconds Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::parseState Jun 20 09:32:38 volumio volumio[1144]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:38 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:38 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:38 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:38 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:38 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:38 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:38 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:39 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:39 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:39 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:39 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:39 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:39 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:39 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:39 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: ------------------------------ 115ms Jun 20 09:32:39 volumio volumio[1144]: info: ------------------------------ 117ms Jun 20 09:32:39 volumio volumio[1144]: info: ------------------------------ 98ms Jun 20 09:32:39 volumio volumio[1144]: info: ------------------------------ 98ms Jun 20 09:32:39 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 49 milliseconds Jun 20 09:32:39 volumio volumio[1144]: info: sendMpdCommand playlistinfo took 50 milliseconds Jun 20 09:32:39 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:39 volumio volumio[1144]: verbose: ControllerMpd::parseTrackInfo Jun 20 09:32:39 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:39 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:39 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:39 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:39 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: ControllerMpd::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::servicePushState Jun 20 09:32:39 volumio volumio[1144]: info: CorePlayQueue::getTrack 0 Jun 20 09:32:39 volumio volumio[1144]: 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":"Les Musiciens du Louvre/Jürgen Bulgrin/Andrew Wedman/Marita Prohmann/Arend Prohmann - Symphony No.41 in C, K.551 - \"Jupiter\": 3. Menuetto (Allegretto)","artist":"Radio Mozart","album":null,"uri":"http://listen.radionomy.com/Radio-Mozart","trackType":""} Jun 20 09:32:39 volumio volumio[1144]: verbose: CURRENT POSITION 0 Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState stateService play Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::syncState currentStatus play Jun 20 09:32:39 volumio volumio[1144]: info: Received an update from plugin. extracting info from payload Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreStateMachine::pushState Jun 20 09:32:39 volumio volumio[1144]: info: CoreCommandRouter::volumioPushState Jun 20 09:32:39 volumio volumio[1144]: info: ------------------------------ 106ms Jun 20 09:32:39 volumio volumio[1144]: info: ------------------------------ 105ms Jun 20 09:32:49 volumio volumio[1144]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 20 09:32:49 volumio volumio[1144]: info: Retrying Login Due to Network Error Jun 20 09:32:51 volumio volumio[1144]: info: Saving local item radio-favourites Jun 20 09:32:51 volumio volumio[1144]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=140955","favourite":false} Jun 20 09:32:58 volumio volumio[1144]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 09:32:58 volumio volumio[1144]: Error: connect ETIMEDOUT 157.240.16.50:80 Jun 20 09:32:58 volumio volumio[1144]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 20 09:32:58 volumio volumio[1144]: errno: -110, Jun 20 09:32:58 volumio volumio[1144]: code: 'ETIMEDOUT', Jun 20 09:32:58 volumio volumio[1144]: syscall: 'connect', Jun 20 09:32:58 volumio volumio[1144]: address: '157.240.16.50', Jun 20 09:32:58 volumio volumio[1144]: port: 80 Jun 20 09:32:58 volumio volumio[1144]: } Jun 20 09:32:58 volumio volumio[1144]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 09:33:00 volumio sudo[13644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-20 09:32 Jun 20 09:33:00 volumio sudo[13644]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"