-- Logs begin at Mon 2025-06-02 22:47:58 CST, end at Tue 2025-06-03 00:16:21 CST. -- Jun 03 00:15:08 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::ClearQueue Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::serviceStop Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::serviceStop Jun 03 00:15:08 volumio volumio[16737]: info: [1748880908815] ControllerWebradio::stop Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::clearPlayQueue Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::addQueueItems Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::addQueueItems Jun 03 00:15:08 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:08 volumio volumio[16737]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99566190 Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioPlay Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::play index 0 Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::play index undefined Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: [1748880908839] ControllerWebradio::clearAddPlayTrack Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:08 volumio volumio[16737]: info: Jun 03 00:15:08 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:08 volumio volumio[16737]: info: sendMpdCommand stop took 45 milliseconds Jun 03 00:15:08 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:08 volumio volumio[16737]: info: sendMpdCommand stop took 22 milliseconds Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 00:15:08 volumio volumio[16737]: info: Jun 03 00:15:08 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:08 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:08 volumio volumio[16737]: info: Jun 03 00:15:08 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:08 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:08 volumio volumio[16737]: info: Jun 03 00:15:08 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:08 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:08 volumio volumio[16737]: info: sendMpdCommand status took 13 milliseconds Jun 03 00:15:08 volumio volumio[16737]: info: sendMpdCommand clear took 11 milliseconds Jun 03 00:15:08 volumio volumio[16737]: info: sendMpdCommand status took 10 milliseconds Jun 03 00:15:08 volumio volumio[16737]: info: sendMpdCommand status took 6 milliseconds Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99566190" Jun 03 00:15:08 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:08 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:15:08 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:08 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:08 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:08 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:15:08 volumio volumio[16737]: info: ------------------------------ 48ms Jun 03 00:15:08 volumio volumio[16737]: info: ------------------------------ 46ms Jun 03 00:15:08 volumio volumio[16737]: info: Jun 03 00:15:08 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:08 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:08 volumio volumio[16737]: info: Jun 03 00:15:08 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:08 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:08 volumio volumio[16737]: error: updateQueue error: null Jun 03 00:15:08 volumio volumio[16737]: info: ------------------------------ 46ms Jun 03 00:15:09 volumio volumio[16737]: info: Jun 03 00:15:09 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:09 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:09 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 257 milliseconds Jun 03 00:15:09 volumio volumio[16737]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99566190" took 257 milliseconds Jun 03 00:15:09 volumio volumio[16737]: info: ------------------------------ 221ms Jun 03 00:15:09 volumio volumio[16737]: info: ------------------------------ 220ms Jun 03 00:15:09 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:09 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 00:15:09 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:15:09 volumio volumio[16737]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 00:15:09 volumio volumio[16737]: info: ------------------------------ 277ms Jun 03 00:15:09 volumio volumio[16737]: info: Jun 03 00:15:09 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:09 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:09 volumio volumio[16737]: info: Jun 03 00:15:09 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:09 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:09 volumio volumio[16737]: info: ------------------------------ 8ms Jun 03 00:15:09 volumio volumio[16737]: info: sendMpdCommand play took 5 milliseconds Jun 03 00:15:09 volumio volumio[16737]: info: ------------------------------ 3ms Jun 03 00:15:09 volumio volumio[16737]: info: ------------------------------ 3ms Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 10 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 3 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 3 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 2 milliseconds Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 5 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 6 milliseconds Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: 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":"stream","artist":"(#1 - 93/400) Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: 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":"stream","artist":"(#1 - 93/400) Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: 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":"stream","artist":"(#1 - 93/400) Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: 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":"stream","artist":"(#1 - 93/400) Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 43ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 97ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 92ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 93ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 98 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 100 milliseconds Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: 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":"stream","artist":"(#1 - 93/400) Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: 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":"stream","artist":"(#1 - 93/400) Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 255ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 252ms Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 20ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 19 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 18ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 16 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 16ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 15 milliseconds Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:10 volumio volumio[16737]: info: Jun 03 00:15:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 44ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 43 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 36ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 37 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 36ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand status took 36 milliseconds Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"la compilation anni 90 di georgia lee puntata 5 -8015324056922032229","artist":"Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"la compilation anni 90 di georgia lee puntata 5 -8015324056922032229","artist":"Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":595,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"la compilation anni 90 di georgia lee puntata 5 -8015324056922032229","artist":"Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 153ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 152ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 149ms Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 94 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 93 milliseconds Jun 03 00:15:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 94 milliseconds Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":629,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"la compilation anni 90 di georgia lee puntata 5 -8015324056922032229","artist":"Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":629,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"la compilation anni 90 di georgia lee puntata 5 -8015324056922032229","artist":"Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:10 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":629,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"la compilation anni 90 di georgia lee puntata 5 -8015324056922032229","artist":"Radio Pro Music 90s","album":null,"uri":"http://88.198.70.25:8414/stream","trackType":""} Jun 03 00:15:10 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:10 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 253ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 245ms Jun 03 00:15:10 volumio volumio[16737]: info: ------------------------------ 245ms Jun 03 00:15:16 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::ClearQueue Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::serviceStop Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::serviceStop Jun 03 00:15:16 volumio volumio[16737]: info: [1748880916631] ControllerWebradio::stop Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::clearPlayQueue Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::addQueueItems Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::addQueueItems Jun 03 00:15:16 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:16 volumio volumio[16737]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1858024 Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioPlay Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::play index 0 Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::play index undefined Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: [1748880916657] ControllerWebradio::clearAddPlayTrack Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand stop took 57 milliseconds Jun 03 00:15:16 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand stop took 31 milliseconds Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:16 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:16 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand status took 4 milliseconds Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:16 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand clear took 6 milliseconds Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand status took 5 milliseconds Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand status took 4 milliseconds Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1858024" Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:16 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:15:16 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:16 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:16 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:15:16 volumio volumio[16737]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 68ms Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 68ms Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 73ms Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:16 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:16 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:16 volumio volumio[16737]: error: updateQueue error: null Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 71ms Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1858024" took 258 milliseconds Jun 03 00:15:16 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 00:15:16 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:16 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 196ms Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 194ms Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:16 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:16 volumio volumio[16737]: info: Jun 03 00:15:16 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:16 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:16 volumio volumio[16737]: info: sendMpdCommand play took 4 milliseconds Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 4ms Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 2ms Jun 03 00:15:16 volumio volumio[16737]: info: ------------------------------ 1ms Jun 03 00:15:17 volumio volumio[16737]: info: Jun 03 00:15:17 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:17 volumio volumio[16737]: info: Jun 03 00:15:17 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:17 volumio volumio[16737]: info: Jun 03 00:15:17 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand status took 7 milliseconds Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:17 volumio volumio[16737]: info: Jun 03 00:15:17 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand status took 8 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand status took 8 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: 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":"89.238.227.6:8012","artist":"(#1 - 178/3000) Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:17 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:15:17 volumio volumio[16737]: info: ------------------------------ 16ms Jun 03 00:15:17 volumio volumio[16737]: info: Jun 03 00:15:17 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:17 volumio volumio[16737]: info: Jun 03 00:15:17 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand status took 7 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand status took 3 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand status took 2 milliseconds Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: 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":"89.238.227.6:8012","artist":"(#1 - 178/3000) Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:17 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:17 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: 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":"89.238.227.6:8012","artist":"(#1 - 178/3000) Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:17 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:17 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: ------------------------------ 67ms Jun 03 00:15:17 volumio volumio[16737]: info: ------------------------------ 68ms Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 03 00:15:17 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:17 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: 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":"89.238.227.6:8012","artist":"(#1 - 178/3000) Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:17 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:17 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: 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":"89.238.227.6:8012","artist":"(#1 - 178/3000) Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:17 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:17 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: 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":"89.238.227.6:8012","artist":"(#1 - 178/3000) Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:17 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:17 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:17 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:17 volumio volumio[16737]: info: ------------------------------ 164ms Jun 03 00:15:17 volumio volumio[16737]: info: ------------------------------ 159ms Jun 03 00:15:17 volumio volumio[16737]: info: ------------------------------ 158ms Jun 03 00:15:18 volumio volumio[16737]: info: Jun 03 00:15:18 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:18 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:18 volumio volumio[16737]: info: Jun 03 00:15:18 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:18 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:18 volumio volumio[16737]: info: Jun 03 00:15:18 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:18 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:18 volumio volumio[16737]: info: Jun 03 00:15:18 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:18 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:18 volumio volumio[16737]: info: Jun 03 00:15:18 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:18 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:18 volumio volumio[16737]: info: Jun 03 00:15:18 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:18 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:18 volumio volumio[16737]: info: ------------------------------ 29ms Jun 03 00:15:18 volumio volumio[16737]: info: sendMpdCommand status took 5 milliseconds Jun 03 00:15:18 volumio volumio[16737]: info: ------------------------------ 4ms Jun 03 00:15:18 volumio volumio[16737]: info: sendMpdCommand status took 3 milliseconds Jun 03 00:15:18 volumio volumio[16737]: info: ------------------------------ 2ms Jun 03 00:15:18 volumio volumio[16737]: info: sendMpdCommand status took 2 milliseconds Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:18 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 03 00:15:18 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 00:15:18 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:18 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:18 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:18 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:18 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"89.238.227.6:8012","artist":"Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:18 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:18 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:18 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:18 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:18 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"89.238.227.6:8012","artist":"Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:18 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:18 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:18 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:18 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:18 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"89.238.227.6:8012","artist":"Radio Romania Cultural","album":null,"uri":"http://89.238.227.6:8012","trackType":"6:8012"} Jun 03 00:15:18 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:18 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:18 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:18 volumio volumio[16737]: info: ------------------------------ 86ms Jun 03 00:15:18 volumio volumio[16737]: info: ------------------------------ 73ms Jun 03 00:15:18 volumio volumio[16737]: info: ------------------------------ 72ms Jun 03 00:15:36 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::ClearQueue Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::serviceStop Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::serviceStop Jun 03 00:15:36 volumio volumio[16737]: info: [1748880936195] ControllerWebradio::stop Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::clearPlayQueue Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::addQueueItems Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::addQueueItems Jun 03 00:15:36 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:36 volumio volumio[16737]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1591414 Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioPlay Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::play index 0 Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::play index undefined Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: [1748880936227] ControllerWebradio::clearAddPlayTrack Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand stop took 53 milliseconds Jun 03 00:15:36 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand stop took 23 milliseconds Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:36 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:36 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:36 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand status took 7 milliseconds Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand clear took 6 milliseconds Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand status took 5 milliseconds Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1591414" Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:36 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:15:36 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:36 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:36 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 49ms Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 48ms Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:36 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:36 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:36 volumio volumio[16737]: error: updateQueue error: null Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 51ms Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:36 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 728 milliseconds Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1591414" took 728 milliseconds Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 684ms Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 683ms Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:36 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 00:15:36 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:15:36 volumio volumio[16737]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 740ms Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:36 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:36 volumio volumio[16737]: info: Jun 03 00:15:36 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:36 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 5ms Jun 03 00:15:36 volumio volumio[16737]: info: sendMpdCommand play took 4 milliseconds Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 3ms Jun 03 00:15:36 volumio volumio[16737]: info: ------------------------------ 2ms Jun 03 00:15:47 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::ClearQueue Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::clearPlayQueue Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::addQueueItems Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::addQueueItems Jun 03 00:15:47 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:15:47 volumio volumio[16737]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527220 Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPlay Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::play index 0 Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::play index undefined Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:47 volumio volumio[16737]: info: [1748880947357] ControllerWebradio::clearAddPlayTrack Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand stop took 6 milliseconds Jun 03 00:15:47 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:47 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand status took 1 milliseconds Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand clear took 2 milliseconds Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527220" Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:47 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:47 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:47 volumio volumio[16737]: error: updateQueue error: null Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 5ms Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:47 volumio volumio[16737]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 8ms Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99527220" took 253 milliseconds Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:47 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand status took 255 milliseconds Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand status took 254 milliseconds Jun 03 00:15:47 volumio volumio[16737]: info: sendMpdCommand play took 2 milliseconds Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:47 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:47 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:47 volumio volumio[16737]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 03 00:15:47 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::syncState stateService stop Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:47 volumio volumio[16737]: info: No code Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:47 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:47 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:47 volumio volumio[16737]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 03 00:15:47 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::syncState stateService stop Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:47 volumio volumio[16737]: info: No code Jun 03 00:15:47 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:47 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 332ms Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 331ms Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:47 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:47 volumio volumio[16737]: info: Jun 03 00:15:47 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:47 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 81ms Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 3ms Jun 03 00:15:47 volumio volumio[16737]: info: ------------------------------ 2ms Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:49 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 27 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 20 milliseconds Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:49 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:15:49 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:15:49 volumio volumio[16737]: info: Jun 03 00:15:49 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 30 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 16 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 24ms Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 15 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 9ms Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 9 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 7ms Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand status took 6 milliseconds Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:49 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 77ms Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 87ms Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 03 00:15:49 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 49 milliseconds Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:49 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:49 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:49 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:49 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":683,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:49 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":694,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio La Kariñosa FM","album":null,"uri":"http://192.99.16.207:8889/stream","trackType":""} Jun 03 00:15:49 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:15:49 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:15:49 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 267ms Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 263ms Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 262ms Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 246ms Jun 03 00:15:49 volumio volumio[16737]: info: ------------------------------ 244ms Jun 03 00:16:10 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::ClearQueue Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::serviceStop Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::serviceStop Jun 03 00:16:10 volumio volumio[16737]: info: [1748880970036] ControllerWebradio::stop Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::clearPlayQueue Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::addQueueItems Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::addQueueItems Jun 03 00:16:10 volumio volumio[16737]: info: Preload queue cleared Jun 03 00:16:10 volumio volumio[16737]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1682343 Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushQueue Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::saveQueue Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::updateTrackBlock Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrackBlock Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPlay Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::play index 0 Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::stop Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::play index undefined Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: [1748880970049] ControllerWebradio::clearAddPlayTrack Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:16:10 volumio volumio[16737]: info: Jun 03 00:16:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand stop took 40 milliseconds Jun 03 00:16:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand stop took 27 milliseconds Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 00:16:10 volumio volumio[16737]: info: Jun 03 00:16:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:10 volumio volumio[16737]: info: Jun 03 00:16:10 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:10 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand status took 6 milliseconds Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:10 volumio volumio[16737]: info: Jun 03 00:16:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand clear took 20 milliseconds Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand status took 19 milliseconds Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand status took 17 milliseconds Jun 03 00:16:10 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1682343" Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:10 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:16:10 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:10 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:10 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:10 volumio volumio[16737]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 00:16:10 volumio volumio[16737]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 00:16:10 volumio volumio[16737]: info: ------------------------------ 39ms Jun 03 00:16:10 volumio volumio[16737]: info: ------------------------------ 37ms Jun 03 00:16:10 volumio volumio[16737]: info: ------------------------------ 43ms Jun 03 00:16:10 volumio volumio[16737]: info: Jun 03 00:16:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:10 volumio volumio[16737]: info: Jun 03 00:16:10 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:10 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:10 volumio volumio[16737]: error: updateQueue error: null Jun 03 00:16:10 volumio volumio[16737]: info: ------------------------------ 30ms Jun 03 00:16:11 volumio volumio[16737]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1682343" took 1060 milliseconds Jun 03 00:16:11 volumio volumio[16737]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 00:16:11 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:16:11 volumio volumio[16737]: info: Jun 03 00:16:11 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:11 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:11 volumio volumio[16737]: info: ------------------------------ 1045ms Jun 03 00:16:11 volumio volumio[16737]: info: ------------------------------ 1045ms Jun 03 00:16:11 volumio volumio[16737]: info: Jun 03 00:16:11 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:11 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:11 volumio volumio[16737]: info: Jun 03 00:16:11 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:11 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:11 volumio volumio[16737]: info: sendMpdCommand play took 6 milliseconds Jun 03 00:16:11 volumio volumio[16737]: info: ------------------------------ 6ms Jun 03 00:16:11 volumio volumio[16737]: info: ------------------------------ 3ms Jun 03 00:16:11 volumio volumio[16737]: info: ------------------------------ 2ms Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand status took 1 milliseconds Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:12 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand status took 10 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand status took 4 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand status took 2 milliseconds Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:12 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:12 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:12 volumio volumio[16737]: 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":"1992_128.mp3?fromyp=true","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:12 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:12 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:12 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:12 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:12 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:12 volumio volumio[16737]: 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":"1992_128.mp3?fromyp=true","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:12 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:12 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:12 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:12 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:12 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:12 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:12 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:12 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:12 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:12 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:12 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:12 volumio volumio[16737]: info: ------------------------------ 23ms Jun 03 00:16:12 volumio volumio[16737]: info: ------------------------------ 22ms Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:12 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces system playlist update Jun 03 00:16:12 volumio volumio[16737]: info: Ignoring MPD Status Update Jun 03 00:16:12 volumio volumio[16737]: info: Jun 03 00:16:12 volumio volumio[16737]: ---------------------------- MPD announces state update: player Jun 03 00:16:12 volumio volumio[16737]: info: ControllerMpd::getState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:16:12 volumio volumio[16737]: info: ------------------------------ 16ms Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand status took 15 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: ------------------------------ 5ms Jun 03 00:16:12 volumio volumio[16737]: info: sendMpdCommand status took 5 milliseconds Jun 03 00:16:12 volumio volumio[16737]: info: ------------------------------ 4ms Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:12 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:13 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:13 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:13 volumio volumio[16737]: 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":"BMFR - DJ Talya","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:13 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:13 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:13 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:13 volumio volumio[16737]: 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":"128 Kbps","isStreaming":false,"title":"BMFR - DJ Talya","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:13 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:13 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:13 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:13 volumio volumio[16737]: 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":"128 Kbps","isStreaming":false,"title":"BMFR - DJ Talya","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:13 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:13 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: ------------------------------ 79ms Jun 03 00:16:13 volumio volumio[16737]: info: ------------------------------ 78ms Jun 03 00:16:13 volumio volumio[16737]: info: ------------------------------ 74ms Jun 03 00:16:13 volumio volumio[16737]: info: sendMpdCommand status took 109 milliseconds Jun 03 00:16:13 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 113 milliseconds Jun 03 00:16:13 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 113 milliseconds Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::parseState Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:13 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:13 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:13 volumio volumio[16737]: 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":"128 Kbps","isStreaming":false,"title":"BMFR - DJ Talya","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:13 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:13 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:13 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:13 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BMFR - DJ Talya","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:13 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:13 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: ------------------------------ 186ms Jun 03 00:16:13 volumio volumio[16737]: info: ------------------------------ 174ms Jun 03 00:16:13 volumio volumio[16737]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 03 00:16:13 volumio volumio[16737]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:16:13 volumio volumio[16737]: info: ControllerMpd::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::servicePushState Jun 03 00:16:13 volumio volumio[16737]: info: CorePlayQueue::getTrack 0 Jun 03 00:16:13 volumio volumio[16737]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BMFR - DJ Talya","artist":"BluesMusicFan Radio","album":null,"uri":"http://216.235.80.104:80/1992_128.mp3?fromyp=true","trackType":""} Jun 03 00:16:13 volumio volumio[16737]: verbose: CURRENT POSITION 0 Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState stateService play Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:16:13 volumio volumio[16737]: info: Received an update from plugin. extracting info from payload Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreStateMachine::pushState Jun 03 00:16:13 volumio volumio[16737]: info: CoreCommandRouter::volumioPushState Jun 03 00:16:13 volumio volumio[16737]: info: ------------------------------ 206ms Jun 03 00:16:21 volumio volumio[16737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 00:16:21 volumio volumio[16737]: Error: connect ETIMEDOUT 128.242.240.61:80 Jun 03 00:16:21 volumio volumio[16737]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 03 00:16:21 volumio volumio[16737]: errno: -110, Jun 03 00:16:21 volumio volumio[16737]: code: 'ETIMEDOUT', Jun 03 00:16:21 volumio volumio[16737]: syscall: 'connect', Jun 03 00:16:21 volumio volumio[16737]: address: '128.242.240.61', Jun 03 00:16:21 volumio volumio[16737]: port: 80 Jun 03 00:16:21 volumio volumio[16737]: } Jun 03 00:16:21 volumio volumio[16737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 00:16:21 volumio sudo[17099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 00:15 Jun 03 00:16:21 volumio sudo[17099]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"