-- Logs begin at Thu 2025-05-01 14:35:18 CST, end at Thu 2025-05-01 15:44:57 CST. -- May 01 15:43:14 volumio volumio[827]: info: Preload queue cleared May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::stPlaybackTimer May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:14 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:14 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::serviceStop May 01 15:43:14 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::serviceStop May 01 15:43:14 volumio volumio[827]: info: [1746085394873] ControllerWebradio::stop May 01 15:43:14 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:14 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:14 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:14 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:14 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:14 volumio volumio[827]: info: Preload queue cleared May 01 15:43:14 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1872628 May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:14 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:15 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:15 volumio volumio[827]: info: [1746085395042] ControllerWebradio::clearAddPlayTrack May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:15 volumio volumio[827]: info: May 01 15:43:15 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand stop took 209 milliseconds May 01 15:43:15 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand stop took 42 milliseconds May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:15 volumio volumio[827]: info: May 01 15:43:15 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:15 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:15 volumio volumio[827]: info: May 01 15:43:15 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:15 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:15 volumio volumio[827]: info: May 01 15:43:15 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:15 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand status took 66 milliseconds May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand clear took 51 milliseconds May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand status took 40 milliseconds May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand status took 26 milliseconds May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1872628" May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:15 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:15 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:15 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:15 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:15 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:15 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:15 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:15 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:15 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:15 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:15 volumio volumio[827]: info: ------------------------------ 169ms May 01 15:43:15 volumio volumio[827]: info: ------------------------------ 167ms May 01 15:43:15 volumio volumio[827]: info: May 01 15:43:15 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:15 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:15 volumio volumio[827]: info: May 01 15:43:15 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:15 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:15 volumio volumio[827]: error: updateQueue error: null May 01 15:43:15 volumio volumio[827]: info: ------------------------------ 164ms May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand playlistinfo took 149 milliseconds May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:15 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:15 volumio volumio[827]: info: ------------------------------ 232ms May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1872628" took 260 milliseconds May 01 15:43:15 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:15 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:15 volumio volumio[827]: error: updateQueue error: null May 01 15:43:15 volumio volumio[827]: info: ------------------------------ 154ms May 01 15:43:15 volumio volumio[827]: info: ------------------------------ 151ms May 01 15:43:15 volumio volumio[827]: info: sendMpdCommand play took 10 milliseconds May 01 15:43:18 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:18 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:18 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:18 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:18 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:18 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:18 volumio volumio[827]: info: [1746085398358] ControllerWebradio::clearAddPlayTrack May 01 15:43:18 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:18 volumio volumio[827]: info: sendMpdCommand stop took 3 milliseconds May 01 15:43:18 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:18 volumio volumio[827]: info: May 01 15:43:18 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:18 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:18 volumio volumio[827]: info: sendMpdCommand clear took 7 milliseconds May 01 15:43:18 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1872628" May 01 15:43:18 volumio volumio[827]: info: May 01 15:43:18 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:18 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:18 volumio volumio[827]: info: May 01 15:43:18 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:18 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:18 volumio volumio[827]: error: updateQueue error: null May 01 15:43:18 volumio volumio[827]: info: ------------------------------ 24ms May 01 15:43:18 volumio volumio[827]: error: updateQueue error: null May 01 15:43:18 volumio volumio[827]: error: updateQueue error: null May 01 15:43:18 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1872628" took 272 milliseconds May 01 15:43:18 volumio volumio[827]: info: ------------------------------ 269ms May 01 15:43:18 volumio volumio[827]: info: ------------------------------ 261ms May 01 15:43:18 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:18 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:18 volumio volumio[827]: info: sendMpdCommand play took 7 milliseconds May 01 15:43:27 volumio volumio[827]: info: Preload queue cleared May 01 15:43:27 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:27 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:27 volumio volumio[827]: info: Preload queue cleared May 01 15:43:27 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1862581 May 01 15:43:27 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:27 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:27 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:27 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:27 volumio volumio[827]: info: [1746085407236] ControllerWebradio::clearAddPlayTrack May 01 15:43:27 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:27 volumio volumio[827]: info: sendMpdCommand stop took 16 milliseconds May 01 15:43:27 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:27 volumio volumio[827]: info: May 01 15:43:27 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:27 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:27 volumio volumio[827]: info: sendMpdCommand clear took 8 milliseconds May 01 15:43:27 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1862581" May 01 15:43:27 volumio volumio[827]: info: May 01 15:43:27 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:27 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:27 volumio volumio[827]: info: May 01 15:43:27 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:27 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:27 volumio volumio[827]: error: updateQueue error: null May 01 15:43:27 volumio volumio[827]: info: ------------------------------ 33ms May 01 15:43:27 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1862581" took 255 milliseconds May 01 15:43:27 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:27 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:27 volumio volumio[827]: info: May 01 15:43:27 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:27 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:27 volumio volumio[827]: info: ------------------------------ 258ms May 01 15:43:27 volumio volumio[827]: info: ------------------------------ 253ms May 01 15:43:27 volumio volumio[827]: info: May 01 15:43:27 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:27 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:27 volumio volumio[827]: info: May 01 15:43:27 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:27 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:27 volumio volumio[827]: info: sendMpdCommand play took 48 milliseconds May 01 15:43:27 volumio volumio[827]: info: ------------------------------ 47ms May 01 15:43:27 volumio volumio[827]: info: ------------------------------ 33ms May 01 15:43:27 volumio volumio[827]: info: ------------------------------ 29ms May 01 15:43:28 volumio volumio[827]: info: May 01 15:43:28 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:28 volumio volumio[827]: info: May 01 15:43:28 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:28 volumio volumio[827]: info: May 01 15:43:28 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:28 volumio volumio[827]: info: May 01 15:43:28 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand status took 31 milliseconds May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:28 volumio volumio[827]: info: May 01 15:43:28 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand status took 48 milliseconds May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand status took 44 milliseconds May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:28 volumio volumio[827]: info: May 01 15:43:28 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand status took 68 milliseconds May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand playlistinfo took 73 milliseconds May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand status took 65 milliseconds May 01 15:43:28 volumio volumio[827]: info: sendMpdCommand playlistinfo took 44 milliseconds May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:28 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:28 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:28 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:28 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:28 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:28 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:28 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:28 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:28 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:28 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:28 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:28 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:28 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:28 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:28 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:28 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:28 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:28 volumio volumio[827]: info: ------------------------------ 472ms May 01 15:43:29 volumio volumio[827]: info: ------------------------------ 789ms May 01 15:43:29 volumio volumio[827]: info: ------------------------------ 779ms May 01 15:43:29 volumio volumio[827]: info: May 01 15:43:29 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:29 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:29 volumio volumio[827]: info: May 01 15:43:29 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:29 volumio volumio[827]: info: sendMpdCommand status took 721 milliseconds May 01 15:43:29 volumio volumio[827]: info: sendMpdCommand playlistinfo took 697 milliseconds May 01 15:43:29 volumio volumio[827]: info: sendMpdCommand playlistinfo took 695 milliseconds May 01 15:43:29 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:29 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:29 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:29 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:29 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:29 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:29 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:29 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:29 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:29 volumio volumio[827]: info: ------------------------------ 1187ms May 01 15:43:29 volumio volumio[827]: info: ------------------------------ 1162ms May 01 15:43:29 volumio volumio[827]: info: May 01 15:43:29 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:29 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:29 volumio volumio[827]: info: May 01 15:43:29 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:29 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:29 volumio volumio[827]: info: May 01 15:43:29 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:29 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:29 volumio volumio[827]: info: May 01 15:43:29 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:29 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:29 volumio volumio[827]: info: Preload queue cleared May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::stPlaybackTimer May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::serviceStop May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::serviceStop May 01 15:43:29 volumio volumio[827]: info: [1746085409828] ControllerWebradio::stop May 01 15:43:29 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:29 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:29 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:29 volumio volumio[827]: info: Preload queue cleared May 01 15:43:29 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1862581 May 01 15:43:29 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: [1746085410108] ControllerWebradio::clearAddPlayTrack May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 1148ms May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand status took 1154 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand playlistinfo took 1145 milliseconds May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 556ms May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand status took 514 milliseconds May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 512ms May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand status took 490 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand stop took 384 milliseconds May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand stop took 83 milliseconds May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 1957ms May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:30 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand status took 121 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand playlistinfo took 88 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand playlistinfo took 87 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand playlistinfo took 86 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand clear took 74 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand status took 28 milliseconds May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand status took 21 milliseconds May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1862581" May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:30 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:30 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:30 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 1391ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 771ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 732ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 174ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 165ms May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:30 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:30 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:30 volumio volumio[827]: error: updateQueue error: null May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 185ms May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand playlistinfo took 174 milliseconds May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:30 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 358ms May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1862581" took 275 milliseconds May 01 15:43:30 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:30 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:30 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 155ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 151ms May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:30 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:30 volumio volumio[827]: info: May 01 15:43:30 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:30 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:30 volumio volumio[827]: info: sendMpdCommand play took 50 milliseconds May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 49ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 24ms May 01 15:43:30 volumio volumio[827]: info: ------------------------------ 21ms May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand status took 169 milliseconds May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand status took 120 milliseconds May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:31 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand status took 179 milliseconds May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand status took 144 milliseconds May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand playlistinfo took 121 milliseconds May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand playlistinfo took 113 milliseconds May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: sendMpdCommand status took 85 milliseconds May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:31 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:31 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:31 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:31 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:31 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:31 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:31 volumio volumio[827]: 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":"stream","artist":"(#1 - 273/1000) GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:31 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:31 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:31 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:31 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:31 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:31 volumio volumio[827]: info: ------------------------------ 500ms May 01 15:43:31 volumio volumio[827]: info: ------------------------------ 571ms May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:31 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:31 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:31 volumio volumio[827]: info: May 01 15:43:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand status took 398 milliseconds May 01 15:43:32 volumio volumio[827]: info: ------------------------------ 369ms May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand status took 346 milliseconds May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand playlistinfo took 344 milliseconds May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand playlistinfo took 318 milliseconds May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand playlistinfo took 305 milliseconds May 01 15:43:32 volumio volumio[827]: info: ------------------------------ 96ms May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand status took 83 milliseconds May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:32 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:32 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:32 volumio volumio[827]: 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":"Brazilian Tropical Orchestra - Promises, Promises","artist":"GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:32 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:32 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:32 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:32 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:32 volumio volumio[827]: 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":"Brazilian Tropical Orchestra - Promises, Promises","artist":"GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:32 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:32 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:32 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:32 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:32 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":525,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"29 Kbps","isStreaming":false,"title":"Brazilian Tropical Orchestra - Promises, Promises","artist":"GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:32 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:32 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:32 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:32 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:32 volumio volumio[827]: info: ------------------------------ 1374ms May 01 15:43:32 volumio volumio[827]: info: ------------------------------ 1280ms May 01 15:43:32 volumio volumio[827]: info: ------------------------------ 1229ms May 01 15:43:32 volumio volumio[827]: info: ------------------------------ 1028ms May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand status took 1020 milliseconds May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand playlistinfo took 959 milliseconds May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand playlistinfo took 893 milliseconds May 01 15:43:32 volumio volumio[827]: info: sendMpdCommand playlistinfo took 880 milliseconds May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:32 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:33 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: 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":"29 Kbps","isStreaming":false,"title":"Brazilian Tropical Orchestra - Promises, Promises","artist":"GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:33 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:33 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: 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":"29 Kbps","isStreaming":false,"title":"Brazilian Tropical Orchestra - Promises, Promises","artist":"GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:33 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:33 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1177,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"29 Kbps","isStreaming":false,"title":"Brazilian Tropical Orchestra - Promises, Promises","artist":"GOLD INSTRUMENTAL","album":null,"uri":"http://199.233.234.34:25373/stream","trackType":""} May 01 15:43:33 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:33 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: ------------------------------ 1955ms May 01 15:43:33 volumio volumio[827]: info: ------------------------------ 1908ms May 01 15:43:33 volumio volumio[827]: info: ------------------------------ 1639ms May 01 15:43:33 volumio volumio[827]: info: Preload queue cleared May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::stPlaybackTimer May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::serviceStop May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::serviceStop May 01 15:43:33 volumio volumio[827]: info: [1746085413680] ControllerWebradio::stop May 01 15:43:33 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:33 volumio volumio[827]: info: Preload queue cleared May 01 15:43:33 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022 May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:33 volumio volumio[827]: info: Preload queue cleared May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:33 volumio volumio[827]: info: Preload queue cleared May 01 15:43:33 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022 May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: [1746085413926] ControllerWebradio::clearAddPlayTrack May 01 15:43:33 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: [1746085413936] ControllerWebradio::clearAddPlayTrack May 01 15:43:33 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:33 volumio volumio[827]: info: May 01 15:43:33 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:33 volumio volumio[827]: info: sendMpdCommand playlistinfo took 965 milliseconds May 01 15:43:33 volumio volumio[827]: info: sendMpdCommand stop took 277 milliseconds May 01 15:43:33 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:33 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:33 volumio volumio[827]: info: sendMpdCommand stop took 38 milliseconds May 01 15:43:33 volumio volumio[827]: info: sendMpdCommand stop took 29 milliseconds May 01 15:43:33 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:33 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:33 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:33 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:33 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:33 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:33 volumio volumio[827]: info: ------------------------------ 2046ms May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand status took 104 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand clear took 95 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand clear took 96 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand status took 31 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand status took 28 milliseconds May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:34 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:34 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 157ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 153ms May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: error: updateQueue error: null May 01 15:43:34 volumio volumio[827]: error: updateQueue error: null May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 169ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 167ms May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand playlistinfo took 155 milliseconds May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:34 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 281ms May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" took 258 milliseconds May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" took 541 milliseconds May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 425ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 421ms May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand play took 285 milliseconds May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 92ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 83ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 80ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 64ms May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand play took 51 milliseconds May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 47ms May 01 15:43:34 volumio volumio[827]: info: ------------------------------ 39ms May 01 15:43:34 volumio volumio[827]: info: Preload queue cleared May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:34 volumio volumio[827]: info: Preload queue cleared May 01 15:43:34 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022 May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:34 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:34 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:34 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:34 volumio volumio[827]: info: [1746085414860] ControllerWebradio::clearAddPlayTrack May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand stop took 51 milliseconds May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:34 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:34 volumio volumio[827]: info: May 01 15:43:34 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:34 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand status took 56 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand clear took 56 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand status took 41 milliseconds May 01 15:43:34 volumio volumio[827]: info: sendMpdCommand status took 31 milliseconds May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" May 01 15:43:34 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:35 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:35 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:35 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:35 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:35 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:35 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:35 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:35 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:35 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:35 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:35 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:35 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:35 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:35 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:35 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 278ms May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 263ms May 01 15:43:35 volumio volumio[827]: info: May 01 15:43:35 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:35 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:35 volumio volumio[827]: info: May 01 15:43:35 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:35 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:35 volumio volumio[827]: error: updateQueue error: null May 01 15:43:35 volumio volumio[827]: info: May 01 15:43:35 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:35 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 339ms May 01 15:43:35 volumio volumio[827]: info: sendMpdCommand playlistinfo took 334 milliseconds May 01 15:43:35 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" took 334 milliseconds May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 110ms May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 50ms May 01 15:43:35 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:35 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:35 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:35 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 422ms May 01 15:43:35 volumio volumio[827]: info: May 01 15:43:35 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:35 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:35 volumio volumio[827]: info: May 01 15:43:35 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:35 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 124ms May 01 15:43:35 volumio volumio[827]: info: sendMpdCommand play took 86 milliseconds May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 69ms May 01 15:43:35 volumio volumio[827]: info: ------------------------------ 54ms May 01 15:43:37 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:37 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:37 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:37 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:37 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:37 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:37 volumio volumio[827]: info: [1746085417149] ControllerWebradio::clearAddPlayTrack May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand stop took 9 milliseconds May 01 15:43:37 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:37 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:37 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:37 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand status took 42 milliseconds May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand clear took 44 milliseconds May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand status took 31 milliseconds May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand status took 13 milliseconds May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:37 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:37 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:37 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:37 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:37 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:37 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:37 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:37 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:37 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:37 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:37 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:37 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:37 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:37 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:37 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 140ms May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 134ms May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:37 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:37 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:37 volumio volumio[827]: error: updateQueue error: null May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 131ms May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:37 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand playlistinfo took 256 milliseconds May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1692022" took 257 milliseconds May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:37 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:37 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:37 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 324ms May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 193ms May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 191ms May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 50ms May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:37 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:37 volumio volumio[827]: info: May 01 15:43:37 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:37 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:37 volumio volumio[827]: info: sendMpdCommand play took 95 milliseconds May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 40ms May 01 15:43:37 volumio volumio[827]: info: ------------------------------ 19ms May 01 15:43:41 volumio volumio[827]: info: May 01 15:43:41 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:41 volumio volumio[827]: info: May 01 15:43:41 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:41 volumio volumio[827]: info: May 01 15:43:41 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:41 volumio volumio[827]: info: May 01 15:43:41 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand status took 41 milliseconds May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand status took 31 milliseconds May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:41 volumio volumio[827]: info: May 01 15:43:41 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:41 volumio volumio[827]: info: May 01 15:43:41 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand status took 66 milliseconds May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand status took 49 milliseconds May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand playlistinfo took 47 milliseconds May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand playlistinfo took 28 milliseconds May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand status took 22 milliseconds May 01 15:43:41 volumio volumio[827]: info: sendMpdCommand status took 18 milliseconds May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:41 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:41 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:41 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:41 volumio volumio[827]: 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":"bossanova_64","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_64","trackType":""} May 01 15:43:41 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:41 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:41 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop May 01 15:43:41 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:41 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:41 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:41 volumio volumio[827]: 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":"bossanova_64","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_64","trackType":""} May 01 15:43:41 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:41 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:41 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:41 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:41 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:41 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:41 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:41 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:41 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:41 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:41 volumio volumio[827]: info: ------------------------------ 186ms May 01 15:43:42 volumio volumio[827]: info: ------------------------------ 309ms May 01 15:43:42 volumio volumio[827]: info: May 01 15:43:42 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:42 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:42 volumio volumio[827]: info: May 01 15:43:42 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:42 volumio volumio[827]: info: sendMpdCommand playlistinfo took 254 milliseconds May 01 15:43:42 volumio volumio[827]: info: sendMpdCommand playlistinfo took 253 milliseconds May 01 15:43:42 volumio volumio[827]: info: sendMpdCommand playlistinfo took 247 milliseconds May 01 15:43:42 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:42 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:42 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:42 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: 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":"bossanova_64","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_64","trackType":""} May 01 15:43:42 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:42 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: 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":"bossanova_64","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_64","trackType":""} May 01 15:43:42 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:42 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: 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":"bossanova_64","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_64","trackType":""} May 01 15:43:42 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:42 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: 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":"bossanova_64","artist":"1.FM - Bossa Nova Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/bossanova_64","trackType":""} May 01 15:43:42 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:42 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:42 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:42 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 1382ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 1386ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 1359ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 1352ms May 01 15:43:43 volumio volumio[827]: info: Preload queue cleared May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioReplaceandPlayItems May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::ClearQueue May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::stPlaybackTimer May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::serviceStop May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::serviceStop May 01 15:43:43 volumio volumio[827]: info: [1746085423467] ControllerWebradio::stop May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::clearPlayQueue May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::addQueueItems May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::addQueueItems May 01 15:43:43 volumio volumio[827]: info: Preload queue cleared May 01 15:43:43 volumio volumio[827]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1530776 May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioPushQueue May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::saveQueue May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::updateTrackBlock May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrackBlock May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioPlay May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::play index 0 May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::stop May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::play index undefined May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::startPlaybackTimer May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: [1746085423626] ControllerWebradio::clearAddPlayTrack May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand stop May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:43 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:43 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 1576ms May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand status took 1540 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand stop took 238 milliseconds May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand stop took 97 milliseconds May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 85ms May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand status took 79 milliseconds May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 67ms May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand status took 65 milliseconds May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand clear May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:43 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand status took 73 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand playlistinfo took 65 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand clear took 65 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand playlistinfo took 65 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand playlistinfo took 61 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand status took 27 milliseconds May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand status took 21 milliseconds May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1530776" May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:43 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:43 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:43 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:43 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:43 volumio volumio[827]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 1735ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 243ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 222ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 157ms May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 153ms May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:43 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:43 volumio volumio[827]: info: May 01 15:43:43 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:43 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:43 volumio volumio[827]: error: updateQueue error: null May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 160ms May 01 15:43:43 volumio volumio[827]: info: sendMpdCommand playlistinfo took 147 milliseconds May 01 15:43:43 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:43 volumio volumio[827]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 01 15:43:43 volumio volumio[827]: info: ------------------------------ 271ms May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:44 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1530776" took 287 milliseconds May 01 15:43:44 volumio volumio[827]: info: ------------------------------ 166ms May 01 15:43:44 volumio volumio[827]: info: ------------------------------ 161ms May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand play May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:44 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:44 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:44 volumio volumio[827]: info: ------------------------------ 80ms May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand play took 45 milliseconds May 01 15:43:44 volumio volumio[827]: info: ------------------------------ 34ms May 01 15:43:44 volumio volumio[827]: info: ------------------------------ 31ms May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand status took 82 milliseconds May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand status took 77 milliseconds May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:44 volumio volumio[827]: info: May 01 15:43:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand status took 143 milliseconds May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand status took 84 milliseconds May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand playlistinfo took 65 milliseconds May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand status took 53 milliseconds May 01 15:43:44 volumio volumio[827]: info: sendMpdCommand status took 49 milliseconds May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:44 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:44 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:44 volumio volumio[827]: 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":"85.195.88.149:28768","artist":"(#1 - 289/800) Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:44 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus stop May 01 15:43:44 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:44 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:44 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:44 volumio volumio[827]: 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":"85.195.88.149:28768","artist":"(#1 - 289/800) Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:44 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:44 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:44 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:44 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:44 volumio volumio[827]: info: ------------------------------ 336ms May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 417ms May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:45 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:45 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 264 milliseconds May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 252 milliseconds May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 243 milliseconds May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 259 milliseconds May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: 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":"85.195.88.149:28768","artist":"(#1 - 289/800) Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:45 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:45 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: 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":"85.195.88.149:28768","artist":"(#1 - 289/800) Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:45 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:45 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: 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":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:45 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:45 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: 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":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:45 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:45 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 1112ms May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 1063ms May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 1021ms May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 1021ms May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:45 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:45 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 809ms May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand status took 796 milliseconds May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 803ms May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand status took 782 milliseconds May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 93ms May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand status took 86 milliseconds May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 81ms May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand status took 75 milliseconds May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:45 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:43:45 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:43:45 volumio volumio[827]: info: May 01 15:43:45 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::getState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 56 milliseconds May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 59 milliseconds May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 59 milliseconds May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand playlistinfo took 45 milliseconds May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 38ms May 01 15:43:45 volumio volumio[827]: info: sendMpdCommand status took 31 milliseconds May 01 15:43:45 volumio volumio[827]: info: ------------------------------ 26ms May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:45 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:45 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:45 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:45 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1079,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:45 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:45 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:45 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:45 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:46 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:46 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1079,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:46 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:46 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:46 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:46 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1787,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:46 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:46 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:46 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:46 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1787,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:46 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:46 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: ------------------------------ 1312ms May 01 15:43:46 volumio volumio[827]: info: ------------------------------ 1312ms May 01 15:43:46 volumio volumio[827]: info: ------------------------------ 601ms May 01 15:43:46 volumio volumio[827]: info: ------------------------------ 597ms May 01 15:43:46 volumio volumio[827]: info: sendMpdCommand status took 520 milliseconds May 01 15:43:46 volumio volumio[827]: info: sendMpdCommand playlistinfo took 487 milliseconds May 01 15:43:46 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:43:46 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:43:46 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:46 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:46 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:46 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:46 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:46 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: ------------------------------ 718ms May 01 15:43:46 volumio volumio[827]: info: sendMpdCommand playlistinfo took 398 milliseconds May 01 15:43:46 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:43:46 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:43:46 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:43:46 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BLECHVERRÜCKT - Verrückt nach Blech","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:43:46 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:43:46 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:43:46 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:43:46 volumio volumio[827]: info: ------------------------------ 1023ms May 01 15:44:10 volumio ntpd[552]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 01 15:44:31 volumio volumio[827]: info: May 01 15:44:31 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:44:31 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:44:31 volumio volumio[827]: info: May 01 15:44:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:44:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:44:31 volumio volumio[827]: info: May 01 15:44:31 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:44:31 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:44:31 volumio volumio[827]: info: May 01 15:44:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:44:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:44:31 volumio volumio[827]: info: May 01 15:44:31 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:44:31 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:44:31 volumio volumio[827]: info: May 01 15:44:31 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:44:31 volumio volumio[827]: info: ControllerMpd::getState May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:44:31 volumio volumio[827]: info: ------------------------------ 93ms May 01 15:44:31 volumio volumio[827]: info: sendMpdCommand status took 99 milliseconds May 01 15:44:31 volumio volumio[827]: info: ------------------------------ 93ms May 01 15:44:31 volumio volumio[827]: info: sendMpdCommand status took 90 milliseconds May 01 15:44:31 volumio volumio[827]: info: ------------------------------ 73ms May 01 15:44:31 volumio volumio[827]: info: sendMpdCommand status took 53 milliseconds May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:44:31 volumio volumio[827]: info: sendMpdCommand playlistinfo took 50 milliseconds May 01 15:44:31 volumio volumio[827]: info: sendMpdCommand playlistinfo took 36 milliseconds May 01 15:44:31 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:44:31 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:44:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:31 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47284,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"85.195.88.149:28768","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:44:31 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:44:31 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:31 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:44:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:31 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47387,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"85.195.88.149:28768","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:44:31 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:44:31 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:31 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:44:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:31 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47387,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"85.195.88.149:28768","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:44:31 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:44:31 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:31 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:31 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:31 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:31 volumio volumio[827]: info: ------------------------------ 559ms May 01 15:44:31 volumio volumio[827]: info: ------------------------------ 550ms May 01 15:44:31 volumio volumio[827]: info: ------------------------------ 509ms May 01 15:44:44 volumio volumio[827]: info: May 01 15:44:44 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:44:44 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:44:44 volumio volumio[827]: info: May 01 15:44:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:44:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:44:44 volumio volumio[827]: info: May 01 15:44:44 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:44:44 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:44:44 volumio volumio[827]: info: May 01 15:44:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:44:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:44:44 volumio volumio[827]: info: May 01 15:44:44 volumio volumio[827]: ---------------------------- MPD announces system playlist update May 01 15:44:44 volumio volumio[827]: info: Ignoring MPD Status Update May 01 15:44:44 volumio volumio[827]: info: May 01 15:44:44 volumio volumio[827]: ---------------------------- MPD announces state update: player May 01 15:44:44 volumio volumio[827]: info: ControllerMpd::getState May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand status May 01 15:44:44 volumio volumio[827]: info: ------------------------------ 85ms May 01 15:44:44 volumio volumio[827]: info: sendMpdCommand status took 64 milliseconds May 01 15:44:44 volumio volumio[827]: info: ------------------------------ 63ms May 01 15:44:44 volumio volumio[827]: info: sendMpdCommand status took 60 milliseconds May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:44:44 volumio volumio[827]: info: ------------------------------ 74ms May 01 15:44:44 volumio volumio[827]: info: sendMpdCommand status took 53 milliseconds May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::parseState May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 15:44:44 volumio volumio[827]: info: sendMpdCommand playlistinfo took 42 milliseconds May 01 15:44:44 volumio volumio[827]: info: sendMpdCommand playlistinfo took 43 milliseconds May 01 15:44:44 volumio volumio[827]: info: sendMpdCommand playlistinfo took 32 milliseconds May 01 15:44:44 volumio volumio[827]: verbose: ControllerMpd::parseTrackInfo May 01 15:44:44 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:44:44 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:44 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60277,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KUENZ BUAM - Auf der Welt is jetzt kritisch","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:44:44 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:44:44 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:44 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:44:44 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:44 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60277,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KUENZ BUAM - Auf der Welt is jetzt kritisch","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:44:44 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:44:44 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:44 volumio volumio[827]: info: ControllerMpd::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::servicePushState May 01 15:44:44 volumio volumio[827]: info: CorePlayQueue::getTrack 0 May 01 15:44:44 volumio volumio[827]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60277,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KUENZ BUAM - Auf der Welt is jetzt kritisch","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 01 15:44:44 volumio volumio[827]: verbose: CURRENT POSITION 0 May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::syncState stateService play May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::syncState currentStatus play May 01 15:44:44 volumio volumio[827]: info: Received an update from plugin. extracting info from payload May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:44 volumio volumio[827]: info: CoreStateMachine::pushState May 01 15:44:44 volumio volumio[827]: info: CoreCommandRouter::volumioPushState May 01 15:44:44 volumio volumio[827]: info: ------------------------------ 445ms May 01 15:44:44 volumio volumio[827]: info: ------------------------------ 427ms May 01 15:44:44 volumio volumio[827]: info: ------------------------------ 402ms May 01 15:44:54 volumio volumio[827]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 15:44:54 volumio volumio[827]: Error: connect ETIMEDOUT 173.252.88.67:80 May 01 15:44:54 volumio volumio[827]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 01 15:44:54 volumio volumio[827]: errno: -110, May 01 15:44:54 volumio volumio[827]: code: 'ETIMEDOUT', May 01 15:44:54 volumio volumio[827]: syscall: 'connect', May 01 15:44:54 volumio volumio[827]: address: '173.252.88.67', May 01 15:44:54 volumio volumio[827]: port: 80 May 01 15:44:54 volumio volumio[827]: } May 01 15:44:54 volumio volumio[827]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 15:44:57 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 15:43 May 01 15:44:57 volumio sudo[2266]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"