-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Wed 2026-02-11 21:10:50 MSK. -- Feb 11 21:09:01 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:01 volumio volumio[5962]: info: [1770833341698] ControllerWebradio::stop Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:01 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:01 volumio volumio[5962]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=191920 Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: [1770833341737] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand stop took 47 milliseconds Feb 11 21:09:01 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand stop took 10 milliseconds Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:01 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:01 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:01 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand status took 10 milliseconds Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand clear took 8 milliseconds Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=191920" Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:01 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:01 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:01 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:01 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 54ms Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 53ms Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:01 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:01 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:01 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 57ms Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 53 milliseconds Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:01 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 68ms Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=191920" took 135 milliseconds Feb 11 21:09:01 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:01 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:01 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 93ms Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 92ms Feb 11 21:09:01 volumio volumio[5962]: info: sendMpdCommand play took 4 milliseconds Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:01 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:01 volumio volumio[5962]: info: Feb 11 21:09:01 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:01 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 9ms Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 5ms Feb 11 21:09:01 volumio volumio[5962]: info: ------------------------------ 4ms Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 12 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 14 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 12 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 4 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: 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 - 33/500) Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 24ms Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 4 milliseconds Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: 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 - 33/500) Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: 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 - 33/500) Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: 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 - 33/500) Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 188ms Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 188ms Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 181ms Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:02 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 163 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 163 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: 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 - 33/500) Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: 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 - 33/500) Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 266ms Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 266ms Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:02 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:02 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:02 volumio volumio[5962]: info: Feb 11 21:09:02 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 146ms Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 144 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 14ms Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 13 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 12ms Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand status took 10 milliseconds Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 11 21:09:02 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dream Drums, Deepvibes Radio 29-11-25","artist":"Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1105,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dream Drums, Deepvibes Radio 29-11-25","artist":"Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:02 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:02 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1105,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dream Drums, Deepvibes Radio 29-11-25","artist":"Deepvibes Radio","album":null,"uri":"http://88.208.218.19:8090/stream","trackType":""} Feb 11 21:09:02 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:02 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:02 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 320ms Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 189ms Feb 11 21:09:02 volumio volumio[5962]: info: ------------------------------ 187ms Feb 11 21:09:04 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:04 volumio volumio[5962]: info: [1770833344365] ControllerWebradio::stop Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:04 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:04 volumio volumio[5962]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1116888 Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: [1770833344406] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand stop took 55 milliseconds Feb 11 21:09:04 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand stop took 15 milliseconds Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:04 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:04 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:04 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand status took 10 milliseconds Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand clear took 10 milliseconds Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1116888" Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:04 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:04 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:04 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:04 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 70ms Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 69ms Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:04 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:04 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:04 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 73ms Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 69 milliseconds Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:04 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 85ms Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1116888" took 139 milliseconds Feb 11 21:09:04 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:04 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:04 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 81ms Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 81ms Feb 11 21:09:04 volumio volumio[5962]: info: sendMpdCommand play took 5 milliseconds Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:04 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:04 volumio volumio[5962]: info: Feb 11 21:09:04 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:04 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 11ms Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 6ms Feb 11 21:09:04 volumio volumio[5962]: info: ------------------------------ 5ms Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand status took 12 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: 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":"costadelmarchillout_32a","artist":"1.FM - Costa Del Mar (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:06 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: 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":"costadelmarchillout_32a","artist":"1.FM - Costa Del Mar (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:06 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:06 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: 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":"costadelmarchillout_32a","artist":"1.FM - Costa Del Mar (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:06 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:06 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: ------------------------------ 65ms Feb 11 21:09:06 volumio volumio[5962]: info: ------------------------------ 139ms Feb 11 21:09:06 volumio volumio[5962]: info: ------------------------------ 139ms Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:06 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:06 volumio volumio[5962]: info: Feb 11 21:09:06 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 119 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:06 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: 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":"costadelmarchillout_32a","artist":"1.FM - Costa Del Mar (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:06 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:06 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: 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":"costadelmarchillout_32a","artist":"1.FM - Costa Del Mar (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:06 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:06 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: 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":"costadelmarchillout_32a","artist":"1.FM - Costa Del Mar (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:06 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:06 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:06 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 310ms Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 302ms Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 302ms Feb 11 21:09:07 volumio volumio[5962]: info: Feb 11 21:09:07 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:07 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:07 volumio volumio[5962]: info: Feb 11 21:09:07 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:07 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:07 volumio volumio[5962]: info: Feb 11 21:09:07 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:07 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:07 volumio volumio[5962]: info: Feb 11 21:09:07 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:07 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 222ms Feb 11 21:09:07 volumio volumio[5962]: info: sendMpdCommand status took 219 milliseconds Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 12ms Feb 11 21:09:07 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 10ms Feb 11 21:09:07 volumio volumio[5962]: info: sendMpdCommand status took 8 milliseconds Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:07 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 11 21:09:07 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 11 21:09:07 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:07 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:07 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:07 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:07 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SAMBOX - Time For A Night","artist":"1.FM - Costa Del Mar","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:07 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:07 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:07 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:07 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SAMBOX - Time For A Night","artist":"1.FM - Costa Del Mar","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:07 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:07 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:07 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:07 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"SAMBOX - Time For A Night","artist":"1.FM - Costa Del Mar","album":null,"uri":"http://185.33.21.112:80/costadelmarchillout_32a","trackType":""} Feb 11 21:09:07 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:07 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 388ms Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 178ms Feb 11 21:09:07 volumio volumio[5962]: info: ------------------------------ 176ms Feb 11 21:09:09 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:09 volumio volumio[5962]: info: [1770833349303] ControllerWebradio::stop Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:09 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:09 volumio volumio[5962]: info: Adding Item to queue: https://api.chilloutradio.ru:8040/live Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: [1770833349348] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:09 volumio volumio[5962]: info: Feb 11 21:09:09 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand stop took 54 milliseconds Feb 11 21:09:09 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand stop took 10 milliseconds Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:09 volumio volumio[5962]: info: Feb 11 21:09:09 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:09 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:09 volumio volumio[5962]: info: Feb 11 21:09:09 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:09 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:09 volumio volumio[5962]: info: Feb 11 21:09:09 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:09 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand clear took 8 milliseconds Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "https://api.chilloutradio.ru:8040/live" Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:09 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:09 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:09 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:09 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:09 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:09 volumio volumio[5962]: info: ------------------------------ 85ms Feb 11 21:09:09 volumio volumio[5962]: info: ------------------------------ 84ms Feb 11 21:09:09 volumio volumio[5962]: info: Feb 11 21:09:09 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:09 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:09 volumio volumio[5962]: info: Feb 11 21:09:09 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:09 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:09 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:09 volumio volumio[5962]: info: ------------------------------ 88ms Feb 11 21:09:09 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 86 milliseconds Feb 11 21:09:09 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:09 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:09 volumio volumio[5962]: info: ------------------------------ 101ms Feb 11 21:09:11 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand add "https://api.chilloutradio.ru:8040/live" Feb 11 21:09:11 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:11 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:11 volumio volumio[5962]: info: Feb 11 21:09:11 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:11 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:11 volumio volumio[5962]: info: ------------------------------ 2265ms Feb 11 21:09:11 volumio volumio[5962]: info: ------------------------------ 2263ms Feb 11 21:09:11 volumio volumio[5962]: info: sendMpdCommand add "https://api.chilloutradio.ru:8040/live" took 5 milliseconds Feb 11 21:09:11 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:11 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:11 volumio volumio[5962]: info: Feb 11 21:09:11 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:11 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:11 volumio volumio[5962]: info: Feb 11 21:09:11 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:11 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:11 volumio volumio[5962]: info: ------------------------------ 21ms Feb 11 21:09:11 volumio volumio[5962]: info: sendMpdCommand play took 19 milliseconds Feb 11 21:09:11 volumio volumio[5962]: info: ------------------------------ 18ms Feb 11 21:09:11 volumio volumio[5962]: info: ------------------------------ 17ms Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 15 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 15 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 13 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 14 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 8 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: 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":"live","artist":null,"album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: 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":"live","artist":null,"album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: 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":"live","artist":null,"album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 68ms Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 129ms Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 130ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 102 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 101 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 101 milliseconds Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: 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":"live","artist":null,"album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: 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":"live","artist":null,"album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: 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":"live","artist":null,"album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 264ms Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 255ms Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 253ms Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:12 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:12 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:12 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:12 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 19ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 18 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 16ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 14 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 13ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:12 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:12 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:12 volumio volumio[5962]: info: Feb 11 21:09:12 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 29ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 27 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 15ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 14 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 13ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand status took 10 milliseconds Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"That's Cool - Lazy Dreams","artist":"Chillout Radio • ?? ?????????? ?? ?????","album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"That's Cool - Lazy Dreams","artist":"Chillout Radio • ?? ?????????? ?? ?????","album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"That's Cool - Lazy Dreams","artist":"Chillout Radio • ?? ?????????? ?? ?????","album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 195ms Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 191ms Feb 11 21:09:12 volumio volumio[5962]: info: ------------------------------ 188ms Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 153 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 152 milliseconds Feb 11 21:09:12 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 151 milliseconds Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"That's Cool - Lazy Dreams","artist":"Chillout Radio • ?? ?????????? ?? ?????","album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"That's Cool - Lazy Dreams","artist":"Chillout Radio • ?? ?????????? ?? ?????","album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:12 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:12 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"That's Cool - Lazy Dreams","artist":"Chillout Radio • ?? ?????????? ?? ?????","album":null,"uri":"https://api.chilloutradio.ru:8040/live","trackType":""} Feb 11 21:09:12 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:12 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:12 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:13 volumio volumio[5962]: info: ------------------------------ 323ms Feb 11 21:09:13 volumio volumio[5962]: info: ------------------------------ 307ms Feb 11 21:09:13 volumio volumio[5962]: info: ------------------------------ 304ms Feb 11 21:09:16 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:16 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:16 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:16 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:16 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:16 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:16 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:16 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:16 volumio volumio[5962]: info: [1770833356958] ControllerWebradio::stop Feb 11 21:09:16 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:16 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:16 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:16 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:16 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:16 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:16 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:16 volumio volumio[5962]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s224874 Feb 11 21:09:16 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: [1770833357021] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand stop took 71 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand stop took 10 milliseconds Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:17 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 8 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand clear took 8 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1" Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 59ms Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 58ms Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:17 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:17 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:17 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 62ms Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 60 milliseconds Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 73ms Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand add "http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1" Feb 11 21:09:17 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:17 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 304ms Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 302ms Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:17 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand add "http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1" took 5 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:17 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:17 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 8ms Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand play took 8 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 7ms Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 5ms Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 13 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 14 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 12 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 4 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: 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":"1","artist":null,"album":null,"uri":"http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1","trackType":""} Feb 11 21:09:17 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 24ms Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: Feb 11 21:09:17 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand status took 4 milliseconds Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: 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":"1","artist":null,"album":null,"uri":"http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1","trackType":""} Feb 11 21:09:17 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:17 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: 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":"1","artist":null,"album":null,"uri":"http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1","trackType":""} Feb 11 21:09:17 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:17 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: 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":"1","artist":null,"album":null,"uri":"http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1","trackType":""} Feb 11 21:09:17 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:17 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 181ms Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 180ms Feb 11 21:09:17 volumio volumio[5962]: info: ------------------------------ 173ms Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 155 milliseconds Feb 11 21:09:17 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 156 milliseconds Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: 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":"1","artist":null,"album":null,"uri":"http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1","trackType":""} Feb 11 21:09:17 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:17 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: 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":"1","artist":null,"album":null,"uri":"http://usa6.fastcast4u.com/proxy/nhhqucow2?mp=/1","trackType":""} Feb 11 21:09:17 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:17 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:18 volumio volumio[5962]: info: ------------------------------ 255ms Feb 11 21:09:18 volumio volumio[5962]: info: ------------------------------ 254ms Feb 11 21:09:28 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 11 21:09:28 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:29 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 11 21:09:29 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:30 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:30 volumio volumio[5962]: info: [1770833370960] ControllerWebradio::stop Feb 11 21:09:30 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:30 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:30 volumio volumio[5962]: info: Adding Item to queue: http://dancewave.online/dance.flac.ogg Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:30 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:30 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:30 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:31 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:31 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:31 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:31 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:31 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:31 volumio volumio[5962]: info: [1770833371001] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:31 volumio volumio[5962]: info: Feb 11 21:09:31 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:31 volumio volumio[5962]: info: sendMpdCommand stop took 49 milliseconds Feb 11 21:09:31 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:31 volumio volumio[5962]: info: sendMpdCommand stop took 10 milliseconds Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:31 volumio volumio[5962]: info: Feb 11 21:09:31 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:31 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:31 volumio volumio[5962]: info: Feb 11 21:09:31 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:31 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:31 volumio volumio[5962]: info: Feb 11 21:09:31 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:31 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:31 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:31 volumio volumio[5962]: info: sendMpdCommand clear took 21 milliseconds Feb 11 21:09:31 volumio volumio[5962]: info: sendMpdCommand status took 20 milliseconds Feb 11 21:09:31 volumio volumio[5962]: info: sendMpdCommand status took 18 milliseconds Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "http://dancewave.online/dance.flac.ogg" Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:31 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:31 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:31 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:31 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:31 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:31 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:31 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:31 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:31 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:31 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:31 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:31 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:31 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:31 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:31 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:31 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:31 volumio volumio[5962]: info: ------------------------------ 79ms Feb 11 21:09:31 volumio volumio[5962]: info: ------------------------------ 78ms Feb 11 21:09:31 volumio volumio[5962]: info: Feb 11 21:09:31 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:31 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:31 volumio volumio[5962]: info: Feb 11 21:09:31 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:31 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:31 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:31 volumio volumio[5962]: info: ------------------------------ 81ms Feb 11 21:09:32 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 1805 milliseconds Feb 11 21:09:32 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand add "http://dancewave.online/dance.flac.ogg" Feb 11 21:09:32 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:32 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:32 volumio volumio[5962]: info: ------------------------------ 1833ms Feb 11 21:09:32 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:32 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:32 volumio volumio[5962]: info: ------------------------------ 1751ms Feb 11 21:09:32 volumio volumio[5962]: info: ------------------------------ 1749ms Feb 11 21:09:32 volumio volumio[5962]: info: Feb 11 21:09:32 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:32 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:32 volumio volumio[5962]: info: sendMpdCommand add "http://dancewave.online/dance.flac.ogg" took 7 milliseconds Feb 11 21:09:32 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:32 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:32 volumio volumio[5962]: info: Feb 11 21:09:32 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:32 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:32 volumio volumio[5962]: info: Feb 11 21:09:32 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:32 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:32 volumio volumio[5962]: info: ------------------------------ 10ms Feb 11 21:09:32 volumio volumio[5962]: info: sendMpdCommand play took 8 milliseconds Feb 11 21:09:32 volumio volumio[5962]: info: ------------------------------ 6ms Feb 11 21:09:32 volumio volumio[5962]: info: ------------------------------ 4ms Feb 11 21:09:33 volumio volumio[5962]: info: Feb 11 21:09:33 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:33 volumio volumio[5962]: info: Feb 11 21:09:33 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:33 volumio volumio[5962]: info: Feb 11 21:09:33 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:33 volumio volumio[5962]: info: Feb 11 21:09:33 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:33 volumio volumio[5962]: info: Feb 11 21:09:33 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:33 volumio volumio[5962]: info: Feb 11 21:09:33 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand status took 4 milliseconds Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Feb 11 21:09:33 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Feb 11 21:09:33 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:33 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Feb 11 21:09:33 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:33 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: ------------------------------ 61ms Feb 11 21:09:33 volumio volumio[5962]: info: ------------------------------ 108ms Feb 11 21:09:33 volumio volumio[5962]: info: ------------------------------ 107ms Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 92 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 91 milliseconds Feb 11 21:09:33 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 91 milliseconds Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:33 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Feb 11 21:09:33 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:33 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Feb 11 21:09:33 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:33 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Feb 11 21:09:33 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:33 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:33 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:33 volumio volumio[5962]: info: ------------------------------ 226ms Feb 11 21:09:33 volumio volumio[5962]: info: ------------------------------ 220ms Feb 11 21:09:33 volumio volumio[5962]: info: ------------------------------ 220ms Feb 11 21:09:37 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:37 volumio volumio[5962]: info: [1770833377756] ControllerWebradio::stop Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:37 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:37 volumio volumio[5962]: info: Adding Item to queue: http://mscp2.live-streams.nl:8100/flac.flac Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: [1770833377798] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:37 volumio volumio[5962]: info: Feb 11 21:09:37 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand stop took 50 milliseconds Feb 11 21:09:37 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand stop took 9 milliseconds Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:37 volumio volumio[5962]: info: Feb 11 21:09:37 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:37 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:37 volumio volumio[5962]: info: Feb 11 21:09:37 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:37 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:37 volumio volumio[5962]: info: Feb 11 21:09:37 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:37 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand clear took 9 milliseconds Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "http://mscp2.live-streams.nl:8100/flac.flac" Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:37 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:37 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:37 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:37 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:37 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:37 volumio volumio[5962]: info: ------------------------------ 53ms Feb 11 21:09:37 volumio volumio[5962]: info: ------------------------------ 52ms Feb 11 21:09:37 volumio volumio[5962]: info: Feb 11 21:09:37 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:37 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:37 volumio volumio[5962]: info: Feb 11 21:09:37 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:37 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:37 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:37 volumio volumio[5962]: info: ------------------------------ 58ms Feb 11 21:09:37 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 56 milliseconds Feb 11 21:09:37 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:37 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:37 volumio volumio[5962]: info: ------------------------------ 70ms Feb 11 21:09:38 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand add "http://mscp2.live-streams.nl:8100/flac.flac" Feb 11 21:09:38 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:38 volumio volumio[5962]: info: ------------------------------ 980ms Feb 11 21:09:38 volumio volumio[5962]: info: ------------------------------ 979ms Feb 11 21:09:38 volumio volumio[5962]: info: Feb 11 21:09:38 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:38 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:38 volumio volumio[5962]: info: sendMpdCommand add "http://mscp2.live-streams.nl:8100/flac.flac" took 4 milliseconds Feb 11 21:09:38 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:38 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:38 volumio volumio[5962]: info: Feb 11 21:09:38 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:38 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:38 volumio volumio[5962]: info: Feb 11 21:09:38 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:38 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:38 volumio volumio[5962]: info: ------------------------------ 9ms Feb 11 21:09:38 volumio volumio[5962]: info: sendMpdCommand play took 7 milliseconds Feb 11 21:09:38 volumio volumio[5962]: info: ------------------------------ 7ms Feb 11 21:09:38 volumio volumio[5962]: info: ------------------------------ 6ms Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 8 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 60ms Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 127ms Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 126ms Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 111 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 112 milliseconds Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 270ms Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 266ms Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 266ms Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:39 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:39 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:39 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:39 volumio volumio[5962]: info: Feb 11 21:09:39 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 11ms Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 10ms Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 7ms Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Hi Online","album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Hi Online","album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 102ms Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 100ms Feb 11 21:09:39 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 91 milliseconds Feb 11 21:09:39 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:39 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Hi Online","album":null,"uri":"http://mscp2.live-streams.nl:8100/flac.flac","trackType":"flac"} Feb 11 21:09:39 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:39 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:39 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:39 volumio volumio[5962]: info: ------------------------------ 151ms Feb 11 21:09:41 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:41 volumio volumio[5962]: info: [1770833381164] ControllerWebradio::stop Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:41 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:41 volumio volumio[5962]: info: Adding Item to queue: http://secure.live-streams.nl/flac.flac Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: [1770833381201] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:41 volumio volumio[5962]: info: Feb 11 21:09:41 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand stop took 46 milliseconds Feb 11 21:09:41 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand stop took 9 milliseconds Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:41 volumio volumio[5962]: info: Feb 11 21:09:41 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:41 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:41 volumio volumio[5962]: info: Feb 11 21:09:41 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:41 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:41 volumio volumio[5962]: info: Feb 11 21:09:41 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:41 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand status took 8 milliseconds Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand clear took 8 milliseconds Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "http://secure.live-streams.nl/flac.flac" Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:41 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:41 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:41 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:41 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:41 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:41 volumio volumio[5962]: info: ------------------------------ 56ms Feb 11 21:09:41 volumio volumio[5962]: info: ------------------------------ 55ms Feb 11 21:09:41 volumio volumio[5962]: info: Feb 11 21:09:41 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:41 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:41 volumio volumio[5962]: info: Feb 11 21:09:41 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:41 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:41 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:41 volumio volumio[5962]: info: ------------------------------ 62ms Feb 11 21:09:41 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 11 21:09:41 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:41 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:41 volumio volumio[5962]: info: ------------------------------ 73ms Feb 11 21:09:42 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand add "http://secure.live-streams.nl/flac.flac" Feb 11 21:09:42 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:42 volumio volumio[5962]: info: Feb 11 21:09:42 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:42 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:42 volumio volumio[5962]: info: ------------------------------ 1082ms Feb 11 21:09:42 volumio volumio[5962]: info: ------------------------------ 1081ms Feb 11 21:09:42 volumio volumio[5962]: info: sendMpdCommand add "http://secure.live-streams.nl/flac.flac" took 4 milliseconds Feb 11 21:09:42 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:42 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:42 volumio volumio[5962]: info: Feb 11 21:09:42 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:42 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:42 volumio volumio[5962]: info: Feb 11 21:09:42 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:42 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:42 volumio volumio[5962]: info: ------------------------------ 8ms Feb 11 21:09:42 volumio volumio[5962]: info: sendMpdCommand play took 6 milliseconds Feb 11 21:09:42 volumio volumio[5962]: info: ------------------------------ 5ms Feb 11 21:09:42 volumio volumio[5962]: info: ------------------------------ 4ms Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 11 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 13 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 12 milliseconds Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus stop Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 62ms Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 131ms Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 130ms Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:43 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 109 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 110 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 109 milliseconds Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Seven Cities (Original Atlantis Mix)","artist":"Solarstone","album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Seven Cities (Original Atlantis Mix)","artist":"Solarstone","album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 265ms Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 259ms Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 259ms Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:43 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:43 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:43 volumio volumio[5962]: info: Feb 11 21:09:43 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 176ms Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 175 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 12ms Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 27 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 26ms Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand status took 25 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Seven Cities (Original Atlantis Mix)","artist":"Solarstone","album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 279ms Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 92 milliseconds Feb 11 21:09:43 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 92 milliseconds Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Seven Cities (Original Atlantis Mix)","artist":"Solarstone","album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:43 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:43 volumio volumio[5962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Seven Cities (Original Atlantis Mix)","artist":"Solarstone","album":null,"uri":"http://secure.live-streams.nl/flac.flac","trackType":"flac"} Feb 11 21:09:43 volumio volumio[5962]: verbose: CURRENT POSITION 0 Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState stateService play Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:09:43 volumio volumio[5962]: info: Received an update from plugin. extracting info from payload Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:43 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 235ms Feb 11 21:09:43 volumio volumio[5962]: info: ------------------------------ 233ms Feb 11 21:09:55 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::serviceStop Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::serviceStop Feb 11 21:09:55 volumio volumio[5962]: info: [1770833395150] ControllerWebradio::stop Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:09:55 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:09:55 volumio volumio[5962]: info: Adding Item to queue: https://isekoi-radio.com/listen/ambient/ambientradio.flac Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: [1770833395191] ControllerWebradio::clearAddPlayTrack Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:09:55 volumio volumio[5962]: info: Feb 11 21:09:55 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand stop took 50 milliseconds Feb 11 21:09:55 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand stop took 10 milliseconds Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:09:55 volumio volumio[5962]: info: Feb 11 21:09:55 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:55 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:55 volumio volumio[5962]: info: Feb 11 21:09:55 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:09:55 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:09:55 volumio volumio[5962]: info: Feb 11 21:09:55 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:55 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand status took 9 milliseconds Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand clear took 8 milliseconds Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand status took 6 milliseconds Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand status took 5 milliseconds Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "https://isekoi-radio.com/listen/ambient/ambientradio.flac" Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:09:55 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:55 volumio volumio[5962]: info: ControllerMpd::pushState Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::servicePushState Feb 11 21:09:55 volumio volumio[5962]: info: CoreStateMachine::pushState Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: CoreCommandRouter::volumioPushState Feb 11 21:09:55 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:09:55 volumio volumio[5962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 11 21:09:55 volumio volumio[5962]: info: ------------------------------ 50ms Feb 11 21:09:55 volumio volumio[5962]: info: ------------------------------ 49ms Feb 11 21:09:55 volumio volumio[5962]: info: Feb 11 21:09:55 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:55 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:55 volumio volumio[5962]: info: Feb 11 21:09:55 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:55 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:55 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:55 volumio volumio[5962]: info: ------------------------------ 52ms Feb 11 21:09:55 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 50 milliseconds Feb 11 21:09:55 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:09:55 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:09:55 volumio volumio[5962]: info: ------------------------------ 64ms Feb 11 21:09:57 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand add "https://isekoi-radio.com/listen/ambient/ambientradio.flac" Feb 11 21:09:57 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:09:57 volumio volumio[5962]: info: ------------------------------ 2212ms Feb 11 21:09:57 volumio volumio[5962]: info: ------------------------------ 2211ms Feb 11 21:09:57 volumio volumio[5962]: info: Feb 11 21:09:57 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:57 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:57 volumio volumio[5962]: info: sendMpdCommand add "https://isekoi-radio.com/listen/ambient/ambientradio.flac" took 4 milliseconds Feb 11 21:09:57 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 21:09:57 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand play Feb 11 21:09:57 volumio volumio[5962]: info: Feb 11 21:09:57 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:57 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:57 volumio volumio[5962]: info: Feb 11 21:09:57 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:09:57 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:09:57 volumio volumio[5962]: info: ------------------------------ 7ms Feb 11 21:09:57 volumio volumio[5962]: info: sendMpdCommand play took 7 milliseconds Feb 11 21:09:57 volumio volumio[5962]: info: ------------------------------ 6ms Feb 11 21:09:57 volumio volumio[5962]: info: ------------------------------ 4ms Feb 11 21:10:07 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:07 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:10:07 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:07 volumio volumio[5962]: info: Adding Item to queue: https://isekoi-radio.com/listen/isekoi/radio.flac Feb 11 21:10:07 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:10:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:10:07 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:07 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:10:07 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:07 volumio volumio[5962]: info: [1770833407972] ControllerWebradio::clearAddPlayTrack Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:10:07 volumio volumio[5962]: info: Feb 11 21:10:07 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:10:07 volumio volumio[5962]: info: sendMpdCommand stop took 5 milliseconds Feb 11 21:10:07 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 21:10:07 volumio volumio[5962]: info: Feb 11 21:10:07 volumio volumio[5962]: ---------------------------- MPD announces system playlist update Feb 11 21:10:07 volumio volumio[5962]: info: Ignoring MPD Status Update Feb 11 21:10:07 volumio volumio[5962]: info: sendMpdCommand status took 8 milliseconds Feb 11 21:10:07 volumio volumio[5962]: info: sendMpdCommand clear took 9 milliseconds Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::parseState Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand load "https://isekoi-radio.com/listen/isekoi/radio.flac" Feb 11 21:10:07 volumio volumio[5962]: info: Feb 11 21:10:07 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:10:07 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:10:07 volumio volumio[5962]: info: Feb 11 21:10:07 volumio volumio[5962]: ---------------------------- MPD announces state update: player Feb 11 21:10:07 volumio volumio[5962]: info: ControllerMpd::getState Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:10:07 volumio volumio[5962]: error: updateQueue error: null Feb 11 21:10:07 volumio volumio[5962]: info: ------------------------------ 10ms Feb 11 21:10:07 volumio volumio[5962]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 11 21:10:07 volumio volumio[5962]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:10:07 volumio volumio[5962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 11 21:10:07 volumio volumio[5962]: info: ------------------------------ 20ms Feb 11 21:10:13 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:13 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:13 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:10:13 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:13 volumio volumio[5962]: info: Adding Item to queue: https://streamserver.pure-isp.eu/radio/8030/flac Feb 11 21:10:13 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:10:13 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:10:13 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:13 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:10:13 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:13 volumio volumio[5962]: info: [1770833413612] ControllerWebradio::clearAddPlayTrack Feb 11 21:10:13 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:10:17 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:17 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:10:17 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:17 volumio volumio[5962]: info: Adding Item to queue: https://streamserver.pure-isp.eu/radio/8030/flac Feb 11 21:10:17 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:10:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:10:17 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:17 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:10:17 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:17 volumio volumio[5962]: info: [1770833417674] ControllerWebradio::clearAddPlayTrack Feb 11 21:10:17 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:10:25 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:25 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:25 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:10:25 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:25 volumio volumio[5962]: info: Adding Item to queue: http://mscp2.live-streams.nl:8100/flac.flac Feb 11 21:10:25 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:10:25 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:10:25 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:25 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:10:25 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:25 volumio volumio[5962]: info: [1770833425260] ControllerWebradio::clearAddPlayTrack Feb 11 21:10:25 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:10:26 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:26 volumio volumio[5962]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::ClearQueue Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::clearPlayQueue Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:26 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::addQueueItems Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::addQueueItems Feb 11 21:10:26 volumio volumio[5962]: info: Preload queue cleared Feb 11 21:10:26 volumio volumio[5962]: info: Adding Item to queue: http://mscp2.live-streams.nl:8100/flac.flac Feb 11 21:10:26 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 11 21:10:26 volumio volumio[5962]: info: CoreCommandRouter::volumioPushQueue Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::saveQueue Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::updateTrackBlock Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::getTrackBlock Feb 11 21:10:26 volumio volumio[5962]: info: CoreCommandRouter::volumioPlay Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::play index 0 Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::stop Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::play index undefined Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:26 volumio volumio[5962]: info: CoreStateMachine::startPlaybackTimer Feb 11 21:10:26 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:26 volumio volumio[5962]: info: [1770833426963] ControllerWebradio::clearAddPlayTrack Feb 11 21:10:26 volumio volumio[5962]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 21:10:29 volumio mpd_monitor.sh[475]: MPD Monitor Service: MPD Appears to be inactive, restarting Feb 11 21:10:29 volumio systemd[1]: Stopping Music Player Daemon... Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 21:10:32 volumio volumio[5962]: info: Received Get System Info Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 21:10:32 volumio volumio[5962]: info: Discovery: Getting this device information Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::volumioGetState Feb 11 21:10:32 volumio volumio[5962]: info: CorePlayQueue::getTrack 0 Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 21:10:32 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 21:10:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 21:10:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 21:10:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 11 21:10:33 volumio volumio[5962]: info: Received Get System Version Feb 11 21:10:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 21:10:33 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Feb 11 21:10:46 volumio volumio-remote-updater[451]: No test mode Feb 11 21:10:46 volumio volumio-remote-updater[451]: No alpha test mode Feb 11 21:10:46 volumio volumio[5962]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

","title":"Update v3.908","updateavailable":true} Feb 11 21:10:46 volumio volumio[5962]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 21:10:49 volumio volumio[5962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 21:10:49 volumio volumio[5962]: Error: connect ETIMEDOUT 23.73.2.84:443 Feb 11 21:10:49 volumio volumio[5962]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 11 21:10:49 volumio volumio[5962]: errno: -110, Feb 11 21:10:49 volumio volumio[5962]: code: 'ETIMEDOUT', Feb 11 21:10:49 volumio volumio[5962]: syscall: 'connect', Feb 11 21:10:49 volumio volumio[5962]: address: '23.73.2.84', Feb 11 21:10:49 volumio volumio[5962]: port: 443 Feb 11 21:10:49 volumio volumio[5962]: } Feb 11 21:10:49 volumio volumio[5962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 21:10:50 volumio sudo[6800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 21:09 Feb 11 21:10:50 volumio sudo[6800]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"