Feb 10 12:01:06 foodguru volumio[2849]: info: Feb 10 12:01:06 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:06 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:06 foodguru volumio[2849]: info: Feb 10 12:01:06 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:06 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:06 foodguru volumio[2849]: info: Feb 10 12:01:06 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:06 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:06 foodguru volumio[2849]: info: Feb 10 12:01:06 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:06 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:06 foodguru volumio[2849]: info: ------------------------------ 4ms Feb 10 12:01:06 foodguru volumio[2849]: info: sendMpdCommand status took 3 milliseconds Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:06 foodguru volumio[2849]: info: ------------------------------ 4ms Feb 10 12:01:06 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:06 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:06 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:06 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:06 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":138722,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"GLEN HANSARD * ROLL ON SLOW","artist":"Max FM","album":null,"uri":"http://live.radyotvonline.com:8090","trackType":"com:8090"} Feb 10 12:01:06 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:06 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:06 foodguru volumio[2849]: info: ------------------------------ 19ms Feb 10 12:01:06 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 10 12:01:06 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:06 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:06 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:06 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":138722,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"GLEN HANSARD * ROLL ON SLOW","artist":"Max FM","album":null,"uri":"http://live.radyotvonline.com:8090","trackType":"com:8090"} Feb 10 12:01:06 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:06 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:06 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:06 foodguru volumio[2849]: info: ------------------------------ 29ms Feb 10 12:01:06 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:06 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:06 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:06 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:11 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 10 12:01:11 foodguru volumio[2849]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101309 Feb 10 12:01:11 foodguru volumio[2849]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101309 Feb 10 12:01:11 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:01:11 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:01:14 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 10 12:01:14 foodguru volumio[2849]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101309&filter=s:popular Feb 10 12:01:14 foodguru volumio[2849]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101309&filter=s:popular Feb 10 12:01:15 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:01:43 foodguru volumio[2849]: Searching plugin music_service/webradio Feb 10 12:01:43 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 10 12:01:44 foodguru volumio[2849]: Searching plugin music_service/webradio Feb 10 12:01:44 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 10 12:01:45 foodguru volumio[2849]: info: An error occurred while querying SHOUTCAST Feb 10 12:01:45 foodguru volumio[2849]: info: All search sources collected, pushing search results Feb 10 12:01:46 foodguru volumio[2849]: info: An error occurred while querying SHOUTCAST Feb 10 12:01:46 foodguru volumio[2849]: info: All search sources collected, pushing search results Feb 10 12:01:51 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::ClearQueue Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::stop Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::stPlaybackTimer Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::updateTrackBlock Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrackBlock Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::serviceStop Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::serviceStop Feb 10 12:01:51 foodguru volumio[2849]: info: [1770714111118] ControllerWebradio::stop Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::clearPlayQueue Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::saveQueue Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushQueue Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::addQueueItems Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::addQueueItems Feb 10 12:01:51 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:01:51 foodguru volumio[2849]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s91212 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: info: Feb 10 12:01:51 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand stop took 43 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:51 foodguru volumio[2849]: info: Feb 10 12:01:51 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:51 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand status took 9 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand status took 9 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:51 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"GLEN HANSARD * ROLL ON SLOW","artist":"Max FM","album":null,"uri":"http://live.radyotvonline.com:8090","trackType":"com:8090"} Feb 10 12:01:51 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: No code Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"GLEN HANSARD * ROLL ON SLOW","artist":"Max FM","album":null,"uri":"http://live.radyotvonline.com:8090","trackType":"com:8090"} Feb 10 12:01:51 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: No code Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:51 foodguru volumio[2849]: info: ------------------------------ 35ms Feb 10 12:01:51 foodguru volumio[2849]: info: ------------------------------ 35ms Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushQueue Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::saveQueue Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::updateTrackBlock Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrackBlock Feb 10 12:01:51 foodguru volumio[2849]: info: CoreCommandRouter::volumioPlay Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::play index 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::stop Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::play index undefined Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: CoreStateMachine::startPlaybackTimer Feb 10 12:01:51 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:51 foodguru volumio[2849]: info: [1770714111549] ControllerWebradio::clearAddPlayTrack Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand stop took 2 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 12:01:51 foodguru volumio[2849]: info: Feb 10 12:01:51 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:51 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:51 foodguru volumio[2849]: info: sendMpdCommand clear took 2 milliseconds Feb 10 12:01:51 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand load "http://provisioning.streamtheworld.com/pls/XHLZ.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls" Feb 10 12:01:51 foodguru volumio[2849]: info: Feb 10 12:01:51 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:51 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:51 foodguru volumio[2849]: error: updateQueue error: null Feb 10 12:01:51 foodguru volumio[2849]: info: ------------------------------ 3ms Feb 10 12:01:53 foodguru volumio[2849]: info: Feb 10 12:01:53 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:53 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:53 foodguru volumio[2849]: info: sendMpdCommand load "http://provisioning.streamtheworld.com/pls/XHLZ.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls" took 1657 milliseconds Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand play Feb 10 12:01:53 foodguru volumio[2849]: info: ------------------------------ 1658ms Feb 10 12:01:53 foodguru volumio[2849]: info: ------------------------------ 3ms Feb 10 12:01:53 foodguru volumio[2849]: info: Feb 10 12:01:53 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:53 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:53 foodguru volumio[2849]: info: sendMpdCommand play took 4 milliseconds Feb 10 12:01:53 foodguru volumio[2849]: info: ------------------------------ 2ms Feb 10 12:01:53 foodguru volumio[2849]: info: Feb 10 12:01:53 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:53 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:53 foodguru volumio[2849]: info: Feb 10 12:01:53 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:53 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:53 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:53 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:53 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 12:01:53 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:53 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:53 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:53 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:53 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:53 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC-Bak","artist":null,"album":null,"uri":"http://14103.live.streamtheworld.com:3690/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:53 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:01:53 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:53 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:53 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:53 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC-Bak","artist":null,"album":null,"uri":"http://14103.live.streamtheworld.com:3690/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:53 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:53 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:53 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:53 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:53 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:53 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:53 foodguru volumio[2849]: info: ------------------------------ 17ms Feb 10 12:01:53 foodguru volumio[2849]: info: ------------------------------ 21ms Feb 10 12:01:53 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:53 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: info: Feb 10 12:01:54 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:54 foodguru volumio[2849]: info: Feb 10 12:01:54 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","artist":null,"album":null,"uri":"http://16603.live.streamtheworld.com:80/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:54 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:54 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: ------------------------------ 16ms Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","artist":null,"album":null,"uri":"http://16603.live.streamtheworld.com:80/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:54 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:54 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: ------------------------------ 26ms Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: info: Feb 10 12:01:54 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:54 foodguru volumio[2849]: info: Feb 10 12:01:54 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand status took 2 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:54 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","artist":null,"album":null,"uri":"http://16603.live.streamtheworld.com:3690/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:54 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:54 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"play","position":3,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","artist":null,"album":null,"uri":"http://16603.live.streamtheworld.com:3690/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:54 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService play Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:54 foodguru volumio[2849]: info: Received an update from plugin. extracting info from payload Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:54 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:54 foodguru volumio[2849]: info: ------------------------------ 20ms Feb 10 12:01:54 foodguru volumio[2849]: info: ------------------------------ 19ms Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:54 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: info: Feb 10 12:01:55 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:55 foodguru volumio[2849]: info: Feb 10 12:01:55 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:55 foodguru volumio[2849]: info: Feb 10 12:01:55 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:55 foodguru volumio[2849]: info: Feb 10 12:01:55 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand status took 7 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand status took 7 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand status took 12 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand clearerror took 8 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand status took 8 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand clearerror took 7 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":3,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","artist":null,"album":null,"uri":"http://16603.live.streamtheworld.com:3690/XHLZ_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls&gdpr=0","trackType":""} Feb 10 12:01:55 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus play Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::play index undefined Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::startPlaybackTimer Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: [1770714115081] ControllerWebradio::clearAddPlayTrack Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 12:01:55 foodguru volumio[2849]: info: ------------------------------ 32ms Feb 10 12:01:55 foodguru volumio[2849]: info: ------------------------------ 31ms Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:55 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand clearerror took 32 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand stop took 16 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 10 12:01:55 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:01:55 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:01:55 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:01:55 foodguru volumio[2849]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 10 12:01:55 foodguru volumio[2849]: info: ------------------------------ 59ms Feb 10 12:01:55 foodguru volumio[2849]: info: ------------------------------ 59ms Feb 10 12:01:55 foodguru volumio[2849]: info: Feb 10 12:01:55 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:55 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:55 foodguru volumio[2849]: info: sendMpdCommand clear took 13 milliseconds Feb 10 12:01:55 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand load "http://provisioning.streamtheworld.com/pls/XHLZ.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MDcxNDExMSwiaXNzIjoidGlzcnYifQ.oh_RcdBDSoJLW_qBVpFAilkzGbQ720u29yTtkHH40ls" Feb 10 12:01:55 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:01:55 foodguru volumio[2849]: info: Feb 10 12:01:55 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:01:55 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:01:55 foodguru volumio[2849]: error: updateQueue error: null Feb 10 12:01:55 foodguru volumio[2849]: info: ------------------------------ 9ms Feb 10 12:02:02 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:02:02 foodguru volumio[2849]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 12:02:02 foodguru volumio[2849]: info: CoreStateMachine::ClearQueue Feb 10 12:02:02 foodguru volumio[2849]: info: CoreStateMachine::stop Feb 10 12:02:02 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:02 foodguru volumio[2849]: info: CorePlayQueue::clearPlayQueue Feb 10 12:02:02 foodguru volumio[2849]: info: CorePlayQueue::saveQueue Feb 10 12:02:02 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushQueue Feb 10 12:02:02 foodguru volumio[2849]: info: CoreStateMachine::addQueueItems Feb 10 12:02:02 foodguru volumio[2849]: info: CorePlayQueue::addQueueItems Feb 10 12:02:02 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:02:02 foodguru volumio[2849]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s218627 Feb 10 12:02:02 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 10 12:02:03 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushQueue Feb 10 12:02:03 foodguru volumio[2849]: info: CorePlayQueue::saveQueue Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::updateTrackBlock Feb 10 12:02:03 foodguru volumio[2849]: info: CorePlayQueue::getTrackBlock Feb 10 12:02:03 foodguru volumio[2849]: info: CoreCommandRouter::volumioPlay Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::play index 0 Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::stop Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::play index undefined Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:03 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:03 foodguru volumio[2849]: info: CoreStateMachine::startPlaybackTimer Feb 10 12:02:03 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:03 foodguru volumio[2849]: info: [1770714123016] ControllerWebradio::clearAddPlayTrack Feb 10 12:02:03 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 12:02:11 foodguru systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Feb 10 12:02:11 foodguru systemd[1]: mpd.service: Killing process 3986 (mpd) with signal SIGABRT. Feb 10 12:02:11 foodguru volumio[2849]: error: MPD error: Error: read ECONNRESET Feb 10 12:02:11 foodguru volumio[2849]: error: MPD error: Error: read ECONNRESET Feb 10 12:02:11 foodguru systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Feb 10 12:02:11 foodguru systemd[1]: mpd.service: Failed with result 'watchdog'. Feb 10 12:02:11 foodguru systemd[1]: mpd.service: Consumed 12.883s CPU time. Feb 10 12:02:16 foodguru systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 12:02:16 foodguru sudo[7966]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:02:16 foodguru sudo[7966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 12:02:16 foodguru sudo[7966]: pam_unix(sudo:session): session closed for user root Feb 10 12:02:19 foodguru mpd[7968]: 2026-02-10T12:02:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 12:02:19 foodguru systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 12:02:19 foodguru volumio[2849]: error: MPD error: The expression evaluated to a falsy value: Feb 10 12:02:19 foodguru volumio[2849]: assert.ok(self.idling) Feb 10 12:02:19 foodguru volumio[2849]: error: The expression evaluated to a falsy value: Feb 10 12:02:19 foodguru volumio[2849]: assert.ok(self.idling) Feb 10 12:02:19 foodguru volumio[2849]: error: updateQueue error: null Feb 10 12:02:21 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:02:21 foodguru volumio[2849]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::ClearQueue Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::stop Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::clearPlayQueue Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::saveQueue Feb 10 12:02:21 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushQueue Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::addQueueItems Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::addQueueItems Feb 10 12:02:21 foodguru volumio[2849]: info: Preload queue cleared Feb 10 12:02:21 foodguru volumio[2849]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s285853 Feb 10 12:02:21 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 10 12:02:21 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushQueue Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::saveQueue Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::updateTrackBlock Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::getTrackBlock Feb 10 12:02:21 foodguru volumio[2849]: info: CoreCommandRouter::volumioPlay Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::play index 0 Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::stop Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::play index undefined Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:21 foodguru volumio[2849]: info: CoreStateMachine::startPlaybackTimer Feb 10 12:02:21 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:21 foodguru volumio[2849]: info: [1770714141707] ControllerWebradio::clearAddPlayTrack Feb 10 12:02:21 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 12:02:21 foodguru volumio[2849]: info: sendMpdCommand stop took 3 milliseconds Feb 10 12:02:21 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 12:02:21 foodguru volumio[2849]: info: Feb 10 12:02:21 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:02:21 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:02:21 foodguru volumio[2849]: info: sendMpdCommand clear took 2 milliseconds Feb 10 12:02:21 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand load "http://stream.zeno.fm/hp720uqw34zuv" Feb 10 12:02:21 foodguru volumio[2849]: info: Feb 10 12:02:21 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:02:21 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:02:21 foodguru volumio[2849]: error: updateQueue error: null Feb 10 12:02:21 foodguru volumio[2849]: info: ------------------------------ 4ms Feb 10 12:02:22 foodguru volumio[2849]: error: updateQueue error: null Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand add "http://stream.zeno.fm/hp720uqw34zuv" Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 691ms Feb 10 12:02:22 foodguru volumio[2849]: info: Feb 10 12:02:22 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:02:22 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand add "http://stream.zeno.fm/hp720uqw34zuv" took 2 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand play Feb 10 12:02:22 foodguru volumio[2849]: info: Feb 10 12:02:22 foodguru volumio[2849]: ---------------------------- MPD announces system playlist update Feb 10 12:02:22 foodguru volumio[2849]: info: Ignoring MPD Status Update Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 5ms Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand play took 4 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 2ms Feb 10 12:02:22 foodguru volumio[2849]: info: Feb 10 12:02:22 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:02:22 foodguru volumio[2849]: info: Feb 10 12:02:22 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:02:22 foodguru volumio[2849]: info: Feb 10 12:02:22 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand status took 15 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:02:22 foodguru volumio[2849]: info: Feb 10 12:02:22 foodguru volumio[2849]: ---------------------------- MPD announces state update: player Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::getState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand status Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand status took 19 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand status took 19 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand clearerror took 6 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:22 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"hp720uqw34zuv","artist":null,"album":null,"uri":"http://stream.zeno.fm/hp720uqw34zuv","trackType":""} Feb 10 12:02:22 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: No code Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 39ms Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand status took 34 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand clearerror took 31 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand clearerror took 30 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 28 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseState Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:22 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"hp720uqw34zuv","artist":null,"album":null,"uri":"http://stream.zeno.fm/hp720uqw34zuv","trackType":""} Feb 10 12:02:22 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: No code Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:22 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"hp720uqw34zuv","artist":null,"album":null,"uri":"http://stream.zeno.fm/hp720uqw34zuv","trackType":""} Feb 10 12:02:22 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: No code Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 72ms Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 72ms Feb 10 12:02:22 foodguru volumio[2849]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 10 12:02:22 foodguru volumio[2849]: verbose: ControllerMpd::parseTrackInfo Feb 10 12:02:22 foodguru volumio[2849]: info: ControllerMpd::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::servicePushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: CorePlayQueue::getTrack 0 Feb 10 12:02:22 foodguru volumio[2849]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"hp720uqw34zuv","artist":null,"album":null,"uri":"http://stream.zeno.fm/hp720uqw34zuv","trackType":""} Feb 10 12:02:22 foodguru volumio[2849]: verbose: CURRENT POSITION 0 Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState stateService stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::syncState currentStatus stop Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: No code Feb 10 12:02:22 foodguru volumio[2849]: info: CoreStateMachine::pushState Feb 10 12:02:22 foodguru volumio[2849]: info: CoreCommandRouter::volumioPushState Feb 10 12:02:22 foodguru volumio[2849]: info: ------------------------------ 74ms Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:22 foodguru volumio[2849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 10 12:02:29 foodguru volumio[2849]: Searching plugin music_service/webradio Feb 10 12:02:29 foodguru volumio[2849]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 10 12:02:31 foodguru volumio[2849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 12:02:31 foodguru volumio[2849]: XMLStructuredError: Premature end of data in tag br line 189 (Line: 199, Column: 1) Feb 10 12:02:31 foodguru volumio[2849]: at new XMLStructuredError (/volumio/node_modules/libxmljs/dist/lib/types.js:23:28) Feb 10 12:02:31 foodguru volumio[2849]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:190:23 Feb 10 12:02:31 foodguru volumio[2849]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Feb 10 12:02:31 foodguru volumio[2849]: at /volumio/app/plugins/music_service/webradio/index.js:962:31 Feb 10 12:02:31 foodguru volumio[2849]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Feb 10 12:02:31 foodguru volumio[2849]: domain: 1, Feb 10 12:02:31 foodguru volumio[2849]: code: 77, Feb 10 12:02:31 foodguru volumio[2849]: level: 3, Feb 10 12:02:31 foodguru volumio[2849]: column: 1, Feb 10 12:02:31 foodguru volumio[2849]: file: '', Feb 10 12:02:31 foodguru volumio[2849]: line: 199, Feb 10 12:02:31 foodguru volumio[2849]: str1: 'br', Feb 10 12:02:31 foodguru volumio[2849]: str2: undefined, Feb 10 12:02:31 foodguru volumio[2849]: str3: undefined, Feb 10 12:02:31 foodguru volumio[2849]: int1: 189 Feb 10 12:02:31 foodguru volumio[2849]: } Feb 10 12:02:31 foodguru volumio[2849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 12:02:31 foodguru sudo[8018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 12:01' Feb 10 12:02:31 foodguru sudo[8018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"