Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::play index 1 Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:01 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:01 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 0 Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:01 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 0 Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:01 volumiokwm volumio[1098]: info: [1756625941802] ControllerCalmRadio::stop Aug 31 16:39:01 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:01 volumiokwm volumio[1098]: info: Aug 31 16:39:01 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:01 volumiokwm volumio[1098]: info: sendMpdCommand stop took 10 milliseconds Aug 31 16:39:01 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:01 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:01 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:01 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:01 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:01 volumiokwm volumio[1098]: info: [1756625941814] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:01 volumiokwm volumio[1098]: { Aug 31 16:39:01 volumiokwm volumio[1098]: uri: 'calmradio://10/638', Aug 31 16:39:01 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:01 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:01 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:01 volumiokwm volumio[1098]: name: 'PEACEFUL BACH', Aug 31 16:39:01 volumiokwm volumio[1098]: title: 'PEACEFUL BACH', Aug 31 16:39:01 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/638/d5cf61d83accb5151f24292bed91a9172c7f26c56066cc5df41828731d50.png', Aug 31 16:39:01 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:01 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:01 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:01 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:01 volumiokwm volumio[1098]: } Aug 31 16:39:01 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 10 Chan 638 Aug 31 16:39:02 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/2236/128/stream Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 242 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand stop took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand clear took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/2236/128/stream" Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:02 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:02 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 267ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 28ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 27ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 24ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/2236/128/stream" took 22 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 4ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 11ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 8ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 8ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 8ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand play took 7 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 7 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: 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":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 18ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: 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":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: 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":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: 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":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: 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":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: 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":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 119ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 117ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 118ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 114ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 114ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:02 volumiokwm volumio[1098]: info: Aug 31 16:39:02 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":877,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"PEACEFUL BACH - Sampler","album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 30ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":877,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"PEACEFUL BACH - Sampler","album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":877,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"PEACEFUL BACH - Sampler","album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Aug 31 16:39:02 volumiokwm volumio[1098]: verbose: CURRENT POSITION 1 Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:02 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 70ms Aug 31 16:39:02 volumiokwm volumio[1098]: info: ------------------------------ 69ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::play index 2 Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 1 Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:04 volumiokwm volumio[1098]: info: [1756625944349] ControllerCalmRadio::stop Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand stop took 9 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:04 volumiokwm volumio[1098]: info: [1756625944362] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:04 volumiokwm volumio[1098]: { Aug 31 16:39:04 volumiokwm volumio[1098]: uri: 'calmradio://14/251', Aug 31 16:39:04 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:04 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:04 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:04 volumiokwm volumio[1098]: name: 'LUTE', Aug 31 16:39:04 volumiokwm volumio[1098]: title: 'LUTE', Aug 31 16:39:04 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/251/02bd5390c728202214045047f06757daca085aa4cf7274516bdc1b8f9e94.png', Aug 31 16:39:04 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:04 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:04 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:04 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:04 volumiokwm volumio[1098]: } Aug 31 16:39:04 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 14 Chan 251 Aug 31 16:39:04 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/475/128/stream Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:04 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:04 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand status took 218 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand stop took 5 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand clear took 9 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/475/128/stream" Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:04 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:04 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:04 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:04 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:04 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 245ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 30ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 29ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 21ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/475/128/stream" took 19 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 4ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 12ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 9ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 9ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:04 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Aug 31 16:39:04 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:04 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: sendMpdCommand play took 6 milliseconds Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:04 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 7 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: 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":"LUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: 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":"LUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: 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":"LUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 37ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 61ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 60ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 47 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 46 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: 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":"LUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: 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":"LUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: 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":"LUTE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 117ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 114ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 113ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 11ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 10 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 10ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:05 volumiokwm volumio[1098]: info: Aug 31 16:39:05 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 16ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 12 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 16ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 12 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 8ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Robert Barto - Weiss: Lute Sonatas, Vol. 8 - Weiss: Sonata for lute in F major (London MS No. 14), SC 19 - Sarabande","artist":"CALMRADIO.COM - Lute","album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Robert Barto - Weiss: Lute Sonatas, Vol. 8 - Weiss: Sonata for lute in F major (London MS No. 14), SC 19 - Sarabande","artist":"CALMRADIO.COM - Lute","album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 76ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 75ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 53 milliseconds Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Robert Barto - Weiss: Lute Sonatas, Vol. 8 - Weiss: Sonata for lute in F major (London MS No. 14), SC 19 - Sarabande","artist":"CALMRADIO.COM - Lute","album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Robert Barto - Weiss: Lute Sonatas, Vol. 8 - Weiss: Sonata for lute in F major (London MS No. 14), SC 19 - Sarabande","artist":"CALMRADIO.COM - Lute","album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Robert Barto - Weiss: Lute Sonatas, Vol. 8 - Weiss: Sonata for lute in F major (London MS No. 14), SC 19 - Sarabande","artist":"CALMRADIO.COM - Lute","album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Robert Barto - Weiss: Lute Sonatas, Vol. 8 - Weiss: Sonata for lute in F major (London MS No. 14), SC 19 - Sarabande","artist":"CALMRADIO.COM - Lute","album":null,"uri":"https://streams.calmradio.com/api/475/128/stream","trackType":""} Aug 31 16:39:05 volumiokwm volumio[1098]: verbose: CURRENT POSITION 2 Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:05 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 153ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 152ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 145ms Aug 31 16:39:05 volumiokwm volumio[1098]: info: ------------------------------ 143ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::play index 3 Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 2 Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:11 volumiokwm volumio[1098]: info: [1756625951320] ControllerCalmRadio::stop Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand stop took 11 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:11 volumiokwm volumio[1098]: info: [1756625951333] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:11 volumiokwm volumio[1098]: { Aug 31 16:39:11 volumiokwm volumio[1098]: uri: 'calmradio://2/938', Aug 31 16:39:11 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:11 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:11 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:11 volumiokwm volumio[1098]: name: 'SUPER PEACEFUL GUITAR', Aug 31 16:39:11 volumiokwm volumio[1098]: title: 'SUPER PEACEFUL GUITAR', Aug 31 16:39:11 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/938/a1a8e22ffb35d4cb37741ec3deca42aa465cab61f9211f631474b2de4da5.png', Aug 31 16:39:11 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:11 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:11 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:11 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:11 volumiokwm volumio[1098]: } Aug 31 16:39:11 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 2 Chan 938 Aug 31 16:39:11 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/6028/128/stream Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:11 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:11 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand status took 248 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand stop took 4 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand clear took 4 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/6028/128/stream" Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:11 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:11 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:11 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:11 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:11 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 272ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 27ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 26ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 25ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/6028/128/stream" took 23 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 13ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 11ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 9ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:11 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Aug 31 16:39:11 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:11 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: sendMpdCommand play took 6 milliseconds Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:11 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: 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":"SUPER PEACEFUL GUITAR","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: 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":"SUPER PEACEFUL GUITAR","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 27ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 41ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 35 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 32 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: 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":"SUPER PEACEFUL GUITAR","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: 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":"SUPER PEACEFUL GUITAR","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: 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":"SUPER PEACEFUL GUITAR","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 97ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 97ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 94ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 83 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: 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":"SUPER PEACEFUL GUITAR","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 140ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:12 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:12 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:12 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:12 volumiokwm volumio[1098]: info: Aug 31 16:39:12 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"SUPER PEACEFUL GUITAR - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 32ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"SUPER PEACEFUL GUITAR - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"SUPER PEACEFUL GUITAR - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/6028/128/stream","trackType":""} Aug 31 16:39:12 volumiokwm volumio[1098]: verbose: CURRENT POSITION 3 Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:12 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 73ms Aug 31 16:39:12 volumiokwm volumio[1098]: info: ------------------------------ 72ms Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::play index 4 Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:13 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:13 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:13 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 3 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:14 volumiokwm volumio[1098]: info: [1756625954006] ControllerCalmRadio::stop Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand stop took 11 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: [1756625954018] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:14 volumiokwm volumio[1098]: { Aug 31 16:39:14 volumiokwm volumio[1098]: uri: 'calmradio://30/566', Aug 31 16:39:14 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:14 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:14 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:14 volumiokwm volumio[1098]: name: 'CELTIC HARP', Aug 31 16:39:14 volumiokwm volumio[1098]: title: 'CELTIC HARP', Aug 31 16:39:14 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/566/d04860cb340f1ab7455626bd5d1e6c847ec82954324a5b8dfd79a2aa42a3.png', Aug 31 16:39:14 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:14 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:14 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:14 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:14 volumiokwm volumio[1098]: } Aug 31 16:39:14 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 30 Chan 566 Aug 31 16:39:14 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/1886/128/stream Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 224 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand stop took 1 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand clear took 5 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/1886/128/stream" Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 263ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 38ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 37ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 39ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/1886/128/stream" took 37 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 4ms Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 24ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 22ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 21ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:14 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 12ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand play took 11 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 10ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 8ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: Aug 31 16:39:14 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: 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":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:14 volumiokwm volumio[1098]: info: ------------------------------ 14ms Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: 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":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: 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":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: 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":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:14 volumiokwm volumio[1098]: 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":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:14 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:14 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:14 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 115ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 114ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 114ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 113ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 107 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:15 volumiokwm volumio[1098]: 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":"CELTIC HARP","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:15 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 138ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: Aug 31 16:39:15 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:15 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:15 volumiokwm volumio[1098]: info: Aug 31 16:39:15 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:15 volumiokwm volumio[1098]: info: Aug 31 16:39:15 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:15 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:15 volumiokwm volumio[1098]: info: Aug 31 16:39:15 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:15 volumiokwm volumio[1098]: info: Aug 31 16:39:15 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:15 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:15 volumiokwm volumio[1098]: info: Aug 31 16:39:15 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aryeh Frankfurter - Celtic Harp - The Music of O'Carolan - Squire Wood's Lamentation of the Refusal of his Half Pence","artist":"CELTIC HARP - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:15 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aryeh Frankfurter - Celtic Harp - The Music of O'Carolan - Squire Wood's Lamentation of the Refusal of his Half Pence","artist":"CELTIC HARP - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:15 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Aryeh Frankfurter - Celtic Harp - The Music of O'Carolan - Squire Wood's Lamentation of the Refusal of his Half Pence","artist":"CELTIC HARP - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1886/128/stream","trackType":""} Aug 31 16:39:15 volumiokwm volumio[1098]: verbose: CURRENT POSITION 4 Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:15 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 76ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 76ms Aug 31 16:39:15 volumiokwm volumio[1098]: info: ------------------------------ 74ms Aug 31 16:39:17 volumiokwm systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::play index 5 Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 4 Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:18 volumiokwm volumio[1098]: info: [1756625958456] ControllerCalmRadio::stop Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand stop took 11 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:18 volumiokwm volumio[1098]: info: [1756625958468] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:18 volumiokwm volumio[1098]: { Aug 31 16:39:18 volumiokwm volumio[1098]: uri: 'calmradio://30/568', Aug 31 16:39:18 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:18 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:18 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:18 volumiokwm volumio[1098]: name: 'PEACEFUL CELLO', Aug 31 16:39:18 volumiokwm volumio[1098]: title: 'PEACEFUL CELLO', Aug 31 16:39:18 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/568/d004986b44e05af413e6218dafaf4d9679414d90e366807ba23a3b3ca4fe.png', Aug 31 16:39:18 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:18 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:18 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:18 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:18 volumiokwm volumio[1098]: } Aug 31 16:39:18 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 30 Chan 568 Aug 31 16:39:18 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/1894/128/stream Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:18 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:18 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand status took 226 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand stop took 4 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand clear took 3 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/1894/128/stream" Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:18 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:18 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:18 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:18 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:18 volumiokwm volumio[1098]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 246ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 22ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 21ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 19ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/1894/128/stream" took 18 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 2ms Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 9ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:18 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Aug 31 16:39:18 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:18 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: sendMpdCommand play took 3 milliseconds Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 4ms Aug 31 16:39:18 volumiokwm volumio[1098]: info: ------------------------------ 3ms Aug 31 16:39:20 volumiokwm volumio[1098]: info: Aug 31 16:39:20 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:20 volumiokwm volumio[1098]: info: Aug 31 16:39:20 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:20 volumiokwm volumio[1098]: info: Aug 31 16:39:20 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:20 volumiokwm volumio[1098]: info: Aug 31 16:39:20 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:20 volumiokwm volumio[1098]: info: Aug 31 16:39:20 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:20 volumiokwm volumio[1098]: info: Aug 31 16:39:20 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand status took 7 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:20 volumiokwm volumio[1098]: 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":"PEACEFUL CELLO","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:20 volumiokwm volumio[1098]: 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":"PEACEFUL CELLO","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:20 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:20 volumiokwm volumio[1098]: 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":"PEACEFUL CELLO","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:20 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:20 volumiokwm volumio[1098]: 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":"PEACEFUL CELLO","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:20 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:20 volumiokwm volumio[1098]: 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":"PEACEFUL CELLO","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:20 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:20 volumiokwm volumio[1098]: 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":"PEACEFUL CELLO","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:20 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:20 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:20 volumiokwm volumio[1098]: info: ------------------------------ 38ms Aug 31 16:39:20 volumiokwm volumio[1098]: info: ------------------------------ 120ms Aug 31 16:39:20 volumiokwm volumio[1098]: info: ------------------------------ 119ms Aug 31 16:39:20 volumiokwm volumio[1098]: info: ------------------------------ 118ms Aug 31 16:39:20 volumiokwm volumio[1098]: info: ------------------------------ 117ms Aug 31 16:39:20 volumiokwm volumio[1098]: info: ------------------------------ 117ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:21 volumiokwm volumio[1098]: info: Aug 31 16:39:21 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 11ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand status took 10 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 10ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand status took 9 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 9ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 4ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":787,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gabriel Fauré, Jules Eskin, Seiji Ozawa, Boston Symphony Orchestra - Cello Meditations - Après un rêve, Op. 7, No. 1","artist":"PEACEFUL CELLO - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:21 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gabriel Fauré, Jules Eskin, Seiji Ozawa, Boston Symphony Orchestra - Cello Meditations - Après un rêve, Op. 7, No. 1","artist":"PEACEFUL CELLO - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:21 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gabriel Fauré, Jules Eskin, Seiji Ozawa, Boston Symphony Orchestra - Cello Meditations - Après un rêve, Op. 7, No. 1","artist":"PEACEFUL CELLO - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:21 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gabriel Fauré, Jules Eskin, Seiji Ozawa, Boston Symphony Orchestra - Cello Meditations - Après un rêve, Op. 7, No. 1","artist":"PEACEFUL CELLO - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:21 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gabriel Fauré, Jules Eskin, Seiji Ozawa, Boston Symphony Orchestra - Cello Meditations - Après un rêve, Op. 7, No. 1","artist":"PEACEFUL CELLO - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:21 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 103ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 102ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 101ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 99ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 98ms Aug 31 16:39:21 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 90 milliseconds Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:21 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gabriel Fauré, Jules Eskin, Seiji Ozawa, Boston Symphony Orchestra - Cello Meditations - Après un rêve, Op. 7, No. 1","artist":"PEACEFUL CELLO - Sampler","album":null,"uri":"https://streams.calmradio.com/api/1894/128/stream","trackType":""} Aug 31 16:39:21 volumiokwm volumio[1098]: verbose: CURRENT POSITION 5 Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:21 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:21 volumiokwm volumio[1098]: info: ------------------------------ 122ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::play index 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 5 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:46 volumiokwm volumio[1098]: info: [1756625986587] ControllerCalmRadio::stop Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand stop took 12 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: [1756625986603] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:46 volumiokwm volumio[1098]: { Aug 31 16:39:46 volumiokwm volumio[1098]: uri: 'calmradio://42/1456', Aug 31 16:39:46 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:46 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:46 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:46 volumiokwm volumio[1098]: name: 'GENTLE WATERFALL', Aug 31 16:39:46 volumiokwm volumio[1098]: title: 'GENTLE WATERFALL', Aug 31 16:39:46 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/001/456/9b632533b37271d0b73a594541f21f9535d2b8b254d7954b6e516cedabea.png', Aug 31 16:39:46 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:46 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:46 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:46 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:46 volumiokwm volumio[1098]: } Aug 31 16:39:46 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 42 Chan 1456 Aug 31 16:39:46 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/6488/128/stream Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand status took 277 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand stop took 2 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:46 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:46 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand clear took 9 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/6488/128/stream" Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:46 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:46 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:46 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:46 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:46 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 325ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 45ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 44ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 44ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/6488/128/stream" took 41 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 24ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 19ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 18ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:46 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Aug 31 16:39:46 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:46 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 11ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: sendMpdCommand play took 7 milliseconds Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:46 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 3 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: 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":"GENTLE WATERFALL","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: 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":"GENTLE WATERFALL","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 30ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 42ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 34 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: 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":"GENTLE WATERFALL","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 67ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 9ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 7 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:47 volumiokwm volumio[1098]: info: Aug 31 16:39:47 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 16 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 15ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 14 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 15ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 13 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 8ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"GENTLE WATERFALL - NATURE SOUND - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 51ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"GENTLE WATERFALL - NATURE SOUND - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"GENTLE WATERFALL - NATURE SOUND - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"GENTLE WATERFALL - NATURE SOUND - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"GENTLE WATERFALL - NATURE SOUND - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Advert: - Limited Time Offer - Save on monthly, 1 Year and 2 Year Memberships - Advert:","artist":"GENTLE WATERFALL - NATURE SOUND - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6488/128/stream","trackType":""} Aug 31 16:39:47 volumiokwm volumio[1098]: verbose: CURRENT POSITION 6 Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:47 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 164ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 162ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 162ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 153ms Aug 31 16:39:47 volumiokwm volumio[1098]: info: ------------------------------ 152ms Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPlay Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::play index 7 Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::stop Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::stPlaybackTimer Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::updateTrackBlock Aug 31 16:39:49 volumiokwm volumio[1098]: info: CorePlayQueue::getTrackBlock Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:49 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::serviceStop Aug 31 16:39:49 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 6 Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreCommandRouter::serviceStop Aug 31 16:39:49 volumiokwm volumio[1098]: info: [1756625989768] ControllerCalmRadio::stop Aug 31 16:39:49 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:49 volumiokwm volumio[1098]: info: Aug 31 16:39:49 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:49 volumiokwm volumio[1098]: info: sendMpdCommand stop took 10 milliseconds Aug 31 16:39:49 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:49 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::play index undefined Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 16:39:49 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:49 volumiokwm volumio[1098]: info: CoreStateMachine::startPlaybackTimer Aug 31 16:39:49 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:49 volumiokwm volumio[1098]: info: [1756625989781] ControllerCalmRadio::clearAddPlayTrack Aug 31 16:39:49 volumiokwm volumio[1098]: { Aug 31 16:39:49 volumiokwm volumio[1098]: uri: 'calmradio://48/454', Aug 31 16:39:49 volumiokwm volumio[1098]: service: 'calmradio', Aug 31 16:39:49 volumiokwm volumio[1098]: trackType: 'CalmRadio', Aug 31 16:39:49 volumiokwm volumio[1098]: radioType: 'web', Aug 31 16:39:49 volumiokwm volumio[1098]: name: 'FOREST MORNING', Aug 31 16:39:49 volumiokwm volumio[1098]: title: 'FOREST MORNING', Aug 31 16:39:49 volumiokwm volumio[1098]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/454/0a97d8578e31c5ac38ec2c1dbe51ae60f340663aaf8bed1658705ddc7d51.png', Aug 31 16:39:49 volumiokwm volumio[1098]: type: 'webradio', Aug 31 16:39:49 volumiokwm volumio[1098]: samplerate: '', Aug 31 16:39:49 volumiokwm volumio[1098]: bitdepth: 0, Aug 31 16:39:49 volumiokwm volumio[1098]: channels: 0 Aug 31 16:39:49 volumiokwm volumio[1098]: } Aug 31 16:39:49 volumiokwm volumio[1098]: info: Calm Radio getStreamUrl for Cat 48 Chan 454 Aug 31 16:39:49 volumiokwm volumio[1098]: STREAMURL: https://streams.calmradio.com/api/6212/128/stream Aug 31 16:39:49 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 222 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand stop took 2 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand clear took 14 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 12 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 11 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/6212/128/stream" Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 306ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 82ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 82ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: error: updateQueue error: null Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 79ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand addid "https://streams.calmradio.com/api/6212/128/stream" took 77 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: MPD COMMAND [object Object] Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 25ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 21ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 20ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 11ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand play took 7 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 7ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 5ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 9 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 7 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 8 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 5 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: 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":"FOREST MORNING","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 19ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 10 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: 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":"FOREST MORNING","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: 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":"FOREST MORNING","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: 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":"FOREST MORNING","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: 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":"FOREST MORNING","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 112ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 112ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 112ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 109ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 97 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: 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":"FOREST MORNING","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 134ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces system playlist update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Ignoring MPD Status Update Aug 31 16:39:50 volumiokwm volumio[1098]: info: Aug 31 16:39:50 volumiokwm volumio[1098]: ---------------------------- MPD announces state update: player Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::getState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 6 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 6ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 4 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: ------------------------------ 4ms Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand status took 2 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseState Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: ControllerMpd::parseTrackInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"CALM RADIO - Atmospheres - Forest Morning","artist":"CALM RADIO - FOREST MORNING - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"CALM RADIO - Atmospheres - Forest Morning","artist":"CALM RADIO - FOREST MORNING - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: ControllerMpd::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::servicePushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CorePlayQueue::getTrack 7 Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"CALM RADIO - Atmospheres - Forest Morning","artist":"CALM RADIO - FOREST MORNING - Sampler","album":null,"uri":"https://streams.calmradio.com/api/6212/128/stream","trackType":""} Aug 31 16:39:50 volumiokwm volumio[1098]: verbose: CURRENT POSITION 7 Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState stateService play Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::syncState currentStatus play Aug 31 16:39:50 volumiokwm volumio[1098]: info: Received an update from plugin. extracting info from payload Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreStateMachine::pushState Aug 31 16:39:50 volumiokwm volumio[1098]: info: CoreCommandRouter::volumioPushState Aug 31 16:39:51 volumiokwm volumio[1098]: info: ------------------------------ 64ms Aug 31 16:39:51 volumiokwm volumio[1098]: info: ------------------------------ 63ms Aug 31 16:39:51 volumiokwm volumio[1098]: info: ------------------------------ 62ms Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: Pool taking: 103.131.151.30 Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: Pool taking: 162.159.200.123 Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: Pool skipping: 46.232.60.193 Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: Pool skipping: 122.215.240.51 Aug 31 16:40:06 volumiokwm ntpd[832]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 31 16:40:19 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 16:40:19 volumiokwm volumio[1098]: info: Preload queue cleared Aug 31 16:40:19 volumiokwm systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 31 16:40:19 volumiokwm dbus-daemon[598]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.33' (uid=0 pid=2580 comm="timedatectl show --property=NTPSynchronized --valu") Aug 31 16:40:19 volumiokwm systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 31 16:40:19 volumiokwm dbus-daemon[598]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 16:40:19 volumiokwm systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 31 16:40:19 volumiokwm setdatetime-helper.sh[2579]: Time is not synchronized. Attempting to sync... Aug 31 16:40:19 volumiokwm sudo[2590]: root : unable to resolve host volumiokwm: System error Aug 31 16:40:19 volumiokwm setdatetime-helper.sh[2590]: sudo: unable to resolve host volumiokwm: System error Aug 31 16:40:19 volumiokwm sudo[2590]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 31 Aug 2025 07:40:20 GMT#015' Aug 31 16:40:19 volumiokwm sudo[2590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 16:40:20 volumiokwm setdatetime-helper.sh[2591]: Sun Aug 31 16:40:20 JST 2025 Aug 31 16:40:20 volumiokwm sudo[2590]: pam_unix(sudo:session): session closed for user root Aug 31 16:40:20 volumiokwm setdatetime-helper.sh[2579]: Time synchronized successfully. Aug 31 16:40:20 volumiokwm systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 31 16:40:20 volumiokwm systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 31 16:40:22 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 16:40:22 volumiokwm volumio[1098]: info: Getting webradio selection Aug 31 16:40:22 volumiokwm volumio[1098]: info: Preload queue cleared Aug 31 16:40:29 volumiokwm volumio[1098]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 16:40:30 volumiokwm volumio[1098]: Use XMLElement.setAttribute instead Aug 31 16:40:30 volumiokwm volumio[1098]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:40:30 volumiokwm volumio[1098]: TypeError: children[i].attr(...).value is not a function Aug 31 16:40:30 volumiokwm volumio[1098]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Aug 31 16:40:30 volumiokwm volumio[1098]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 31 16:40:30 volumiokwm volumio[1098]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 16:40:31 volumiokwm sudo[2642]: volumio : unable to resolve host volumiokwm: System error Aug 31 16:40:31 volumiokwm sudo[2642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-31 16:39' Aug 31 16:40:31 volumiokwm sudo[2642]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"