-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2025-10-25 12:59:03 UTC. -- Oct 25 12:58:13 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Oct 25 12:58:13 volumio volumio[824]: info: Calm Radio group browse calmradio://38/6 Oct 25 12:58:14 volumio volumio[824]: info: Calm Radio list channels for group 6 Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::ClearQueue Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:16 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::stPlaybackTimer Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::serviceStop Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::serviceStop Oct 25 12:58:16 volumio volumio[824]: info: [1761397096826] ControllerCalmRadio::stop Oct 25 12:58:16 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::clearPlayQueue Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::addQueueItems Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::addQueueItems Oct 25 12:58:16 volumio volumio[824]: info: Adding Item to queue: calmradio://6/1204 Oct 25 12:58:16 volumio volumio[824]: info: Exploding uri calmradio://6/1204 in service calmradio Oct 25 12:58:16 volumio volumio[824]: info: Calm Radio explodeUri for Cat 6 Chan 1204 Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:16 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 25 12:58:16 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::play index 0 Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:16 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:16 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 25 12:58:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:16 volumio volumio[824]: info: [1761397096976] ControllerCalmRadio::clearAddPlayTrack Oct 25 12:58:16 volumio volumio[824]: { Oct 25 12:58:16 volumio volumio[824]: uri: 'calmradio://6/1204', Oct 25 12:58:16 volumio volumio[824]: service: 'calmradio', Oct 25 12:58:16 volumio volumio[824]: trackType: 'CalmRadio', Oct 25 12:58:16 volumio volumio[824]: radioType: 'web', Oct 25 12:58:16 volumio volumio[824]: name: 'SUMMER TRACKS', Oct 25 12:58:16 volumio volumio[824]: title: 'SUMMER TRACKS', Oct 25 12:58:16 volumio volumio[824]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/001/204/ebc3f334fef95feffc2a46d9d81b1dfdcb3f5bb1f4c63d7b7fb1a701bb48.png', Oct 25 12:58:16 volumio volumio[824]: type: 'webradio', Oct 25 12:58:16 volumio volumio[824]: samplerate: '', Oct 25 12:58:16 volumio volumio[824]: bitdepth: 0, Oct 25 12:58:16 volumio volumio[824]: channels: 0 Oct 25 12:58:16 volumio volumio[824]: } Oct 25 12:58:16 volumio volumio[824]: info: Calm Radio getStreamUrl for Cat 6 Chan 1204 Oct 25 12:58:17 volumio volumio[824]: STREAMURL: https://streams.calmradio.com/api/4688/128/stream Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand stop took 267 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand stop took 8 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 34 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand clear took 33 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 29 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/4688/128/stream" Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 46ms Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: error: updateQueue error: null Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 27ms Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand addid "https://streams.calmradio.com/api/4688/128/stream" took 23 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 9ms Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 12:58:17 volumio volumio[824]: verbose: MPD COMMAND [object Object] Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 65ms Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: info: touch_display: Setting screensaver timeout to 510 seconds. Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 22ms Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 16ms Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand play Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 12ms Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand play took 5 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 3ms Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 19 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 19 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 5 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 2 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: 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":"SUMMER TRACKS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus stop Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: 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":"SUMMER TRACKS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 63ms Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 62ms Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: 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":"SUMMER TRACKS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: 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":"SUMMER TRACKS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 105ms Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 101ms Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 8ms Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 5 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 5ms Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 4 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"SUMMER TRACKS - Sampler","album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 44ms Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"SUMMER TRACKS - Sampler","album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 79ms Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:17 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:17 volumio volumio[824]: info: Oct 25 12:58:17 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 98ms Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 94 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: ------------------------------ 50ms Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand status took 47 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 12:58:17 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":422,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chris Stapleton - Traveller - Tennessee Whiskey","artist":"SUMMER TRACKS - Sampler","album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:17 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:17 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chris Stapleton - Traveller - Tennessee Whiskey","artist":"SUMMER TRACKS - Sampler","album":null,"uri":"https://streams.calmradio.com/api/4688/128/stream","trackType":""} Oct 25 12:58:17 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:17 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:17 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:17 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:18 volumio volumio[824]: info: ------------------------------ 145ms Oct 25 12:58:18 volumio volumio[824]: info: ------------------------------ 97ms Oct 25 12:58:18 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:18 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:18 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:18 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:22 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 12:58:22 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 12:58:22 volumio volumio[824]: info: Discovery: Getting this device information Oct 25 12:58:22 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 25 12:58:22 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 12:58:25 volumio volumio[824]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 12:58:25 volumio volumio[824]: info: CoreStateMachine::ClearQueue Oct 25 12:58:25 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:25 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:25 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:25 volumio volumio[824]: info: CoreStateMachine::stPlaybackTimer Oct 25 12:58:25 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:25 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:25 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:25 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:25 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:25 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::serviceStop Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::serviceStop Oct 25 12:58:26 volumio volumio[824]: info: [1761397106000] ControllerCalmRadio::stop Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::clearPlayQueue Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::addQueueItems Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::addQueueItems Oct 25 12:58:26 volumio volumio[824]: info: Adding Item to queue: calmradio://6/1208 Oct 25 12:58:26 volumio volumio[824]: info: Exploding uri calmradio://6/1208 in service calmradio Oct 25 12:58:26 volumio volumio[824]: info: Calm Radio explodeUri for Cat 6 Chan 1208 Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 25 12:58:26 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::play index 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:26 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: info: [1761397106145] ControllerCalmRadio::clearAddPlayTrack Oct 25 12:58:26 volumio volumio[824]: { Oct 25 12:58:26 volumio volumio[824]: uri: 'calmradio://6/1208', Oct 25 12:58:26 volumio volumio[824]: service: 'calmradio', Oct 25 12:58:26 volumio volumio[824]: trackType: 'CalmRadio', Oct 25 12:58:26 volumio volumio[824]: radioType: 'web', Oct 25 12:58:26 volumio volumio[824]: name: 'MUSIQUE DU QUÉBEC', Oct 25 12:58:26 volumio volumio[824]: title: 'MUSIQUE DU QUÉBEC', Oct 25 12:58:26 volumio volumio[824]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/001/208/dae0720b708b2e718903607745419d5449c337013967179e704b7c6cb4aa.png', Oct 25 12:58:26 volumio volumio[824]: type: 'webradio', Oct 25 12:58:26 volumio volumio[824]: samplerate: '', Oct 25 12:58:26 volumio volumio[824]: bitdepth: 0, Oct 25 12:58:26 volumio volumio[824]: channels: 0 Oct 25 12:58:26 volumio volumio[824]: } Oct 25 12:58:26 volumio volumio[824]: info: Calm Radio getStreamUrl for Cat 6 Chan 1208 Oct 25 12:58:26 volumio volumio[824]: STREAMURL: https://streams.calmradio.com/api/4302/128/stream Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand stop took 280 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand stop took 10 milliseconds Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand status took 30 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand clear took 30 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand status took 29 milliseconds Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/4302/128/stream" Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 43ms Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:26 volumio volumio[824]: error: updateQueue error: null Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 22ms Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand addid "https://streams.calmradio.com/api/4302/128/stream" took 19 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 6ms Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:26 volumio volumio[824]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 12:58:26 volumio volumio[824]: verbose: MPD COMMAND [object Object] Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 53ms Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: info: touch_display: Setting screensaver timeout to 510 seconds. Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 12ms Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 10ms Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand play Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 3ms Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand play took 2 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 3ms Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand status took 20 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand status took 19 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand status took 2 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: 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":"MUSIQUE DU QUÉBEC","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4302/128/stream","trackType":""} Oct 25 12:58:26 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus stop Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: 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":"MUSIQUE DU QUÉBEC","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4302/128/stream","trackType":""} Oct 25 12:58:26 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:26 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 53ms Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 52ms Oct 25 12:58:26 volumio volumio[824]: info: Oct 25 12:58:26 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand status took 3 milliseconds Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: 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":"MUSIQUE DU QUÉBEC","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4302/128/stream","trackType":""} Oct 25 12:58:26 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:26 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 58ms Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 25 12:58:26 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:26 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:26 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:26 volumio volumio[824]: 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":"MUSIQUE DU QUÉBEC","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4302/128/stream","trackType":""} Oct 25 12:58:26 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:26 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:26 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:26 volumio volumio[824]: info: ------------------------------ 52ms Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:26 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:27 volumio volumio[824]: info: Oct 25 12:58:27 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:27 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:27 volumio volumio[824]: info: Oct 25 12:58:27 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:27 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:27 volumio volumio[824]: info: Oct 25 12:58:27 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:27 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:27 volumio volumio[824]: info: Oct 25 12:58:27 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:27 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:27 volumio volumio[824]: info: ------------------------------ 4ms Oct 25 12:58:27 volumio volumio[824]: info: sendMpdCommand status took 3 milliseconds Oct 25 12:58:27 volumio volumio[824]: info: ------------------------------ 3ms Oct 25 12:58:27 volumio volumio[824]: info: sendMpdCommand status took 3 milliseconds Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:27 volumio volumio[824]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 25 12:58:27 volumio volumio[824]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:27 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:27 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:27 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:27 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ghostly Kisses - Don't Know Why - Single - Don't Know Why","artist":"MUSIQUE DU QUEBEC - sampler","album":null,"uri":"https://streams.calmradio.com/api/4302/128/stream","trackType":""} Oct 25 12:58:27 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:27 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:27 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:27 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:27 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ghostly Kisses - Don't Know Why - Single - Don't Know Why","artist":"MUSIQUE DU QUEBEC - sampler","album":null,"uri":"https://streams.calmradio.com/api/4302/128/stream","trackType":""} Oct 25 12:58:27 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:27 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:27 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:27 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:27 volumio volumio[824]: info: ------------------------------ 92ms Oct 25 12:58:27 volumio volumio[824]: info: ------------------------------ 92ms Oct 25 12:58:27 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:27 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:27 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:27 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::ClearQueue Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:31 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::stPlaybackTimer Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::serviceStop Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::serviceStop Oct 25 12:58:31 volumio volumio[824]: info: [1761397111241] ControllerCalmRadio::stop Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::clearPlayQueue Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::addQueueItems Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::addQueueItems Oct 25 12:58:31 volumio volumio[824]: info: Adding Item to queue: calmradio://6/482 Oct 25 12:58:31 volumio volumio[824]: info: Exploding uri calmradio://6/482 in service calmradio Oct 25 12:58:31 volumio volumio[824]: info: Calm Radio explodeUri for Cat 6 Chan 482 Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 25 12:58:31 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::play index 0 Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:31 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:31 volumio volumio[824]: info: [1761397111395] ControllerCalmRadio::clearAddPlayTrack Oct 25 12:58:31 volumio volumio[824]: { Oct 25 12:58:31 volumio volumio[824]: uri: 'calmradio://6/482', Oct 25 12:58:31 volumio volumio[824]: service: 'calmradio', Oct 25 12:58:31 volumio volumio[824]: trackType: 'CalmRadio', Oct 25 12:58:31 volumio volumio[824]: radioType: 'web', Oct 25 12:58:31 volumio volumio[824]: name: 'COFFEE HOUSE', Oct 25 12:58:31 volumio volumio[824]: title: 'COFFEE HOUSE', Oct 25 12:58:31 volumio volumio[824]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/482/3f0ae94a582039a38e917388cb5a1481278cae62dcce2e214f64eb206ccb.png', Oct 25 12:58:31 volumio volumio[824]: type: 'webradio', Oct 25 12:58:31 volumio volumio[824]: samplerate: '', Oct 25 12:58:31 volumio volumio[824]: bitdepth: 0, Oct 25 12:58:31 volumio volumio[824]: channels: 0 Oct 25 12:58:31 volumio volumio[824]: } Oct 25 12:58:31 volumio volumio[824]: info: Calm Radio getStreamUrl for Cat 6 Chan 482 Oct 25 12:58:31 volumio volumio[824]: STREAMURL: https://streams.calmradio.com/api/1210/128/stream Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:31 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:31 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand stop took 278 milliseconds Oct 25 12:58:31 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand stop took 26 milliseconds Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:31 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:31 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand status took 32 milliseconds Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand clear took 32 milliseconds Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand status took 32 milliseconds Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/1210/128/stream" Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:31 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:31 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:31 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:31 volumio volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 65ms Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:31 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:31 volumio volumio[824]: error: updateQueue error: null Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:31 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 53ms Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand addid "https://streams.calmradio.com/api/1210/128/stream" took 50 milliseconds Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 16ms Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:31 volumio volumio[824]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 12:58:31 volumio volumio[824]: verbose: MPD COMMAND [object Object] Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 87ms Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:31 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:31 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:31 volumio volumio[824]: info: touch_display: Setting screensaver timeout to 510 seconds. Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:31 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 12ms Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 9ms Oct 25 12:58:31 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 12:58:31 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand play Oct 25 12:58:31 volumio volumio[824]: info: Oct 25 12:58:31 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:31 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 7ms Oct 25 12:58:31 volumio volumio[824]: info: sendMpdCommand play took 6 milliseconds Oct 25 12:58:31 volumio volumio[824]: info: ------------------------------ 6ms Oct 25 12:58:31 volumio volumio[824]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 25 12:58:31 volumio volumio[824]: info: touch_display: Setting screensaver timeout to 510 seconds. Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 2 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 1 milliseconds Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 3 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 1 milliseconds Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: 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":"COFFEE HOUSE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus stop Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: 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":"COFFEE HOUSE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 41ms Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 41ms Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: 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":"COFFEE HOUSE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: 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":"COFFEE HOUSE","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 87ms Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 86ms Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:32 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:32 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:32 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 5ms Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 5 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 4ms Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 4 milliseconds Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:32 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:32 volumio volumio[824]: info: Oct 25 12:58:32 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 34ms Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 33 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 31ms Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand status took 30 milliseconds Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rosi Golan - Fortuna - Got What We Wanted","artist":"CALMRADIO.COM - Coffee House","album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rosi Golan - Fortuna - Got What We Wanted","artist":"CALMRADIO.COM - Coffee House","album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 84ms Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 83ms Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 25 12:58:32 volumio volumio[824]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rosi Golan - Fortuna - Got What We Wanted","artist":"CALMRADIO.COM - Coffee House","album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:32 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:32 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rosi Golan - Fortuna - Got What We Wanted","artist":"CALMRADIO.COM - Coffee House","album":null,"uri":"https://streams.calmradio.com/api/1210/128/stream","trackType":""} Oct 25 12:58:32 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:32 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:32 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 127ms Oct 25 12:58:32 volumio volumio[824]: info: ------------------------------ 124ms Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:32 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:42 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Oct 25 12:58:42 volumio volumio[824]: info: Calm Radio group browse calmradio://6/-1 Oct 25 12:58:43 volumio volumio[824]: info: Calm Radio list channels for group -1 Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::ClearQueue Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:44 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::stPlaybackTimer Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::serviceStop Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::serviceStop Oct 25 12:58:44 volumio volumio[824]: info: [1761397124781] ControllerCalmRadio::stop Oct 25 12:58:44 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::clearPlayQueue Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::addQueueItems Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::addQueueItems Oct 25 12:58:44 volumio volumio[824]: info: Adding Item to queue: calmradio://6/374 Oct 25 12:58:44 volumio volumio[824]: info: Exploding uri calmradio://6/374 in service calmradio Oct 25 12:58:44 volumio volumio[824]: info: Calm Radio explodeUri for Cat 6 Chan 374 Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::saveQueue Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::volumioPushQueue Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::updateTrackBlock Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::getTrackBlock Oct 25 12:58:44 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 25 12:58:44 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::play index 0 Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::stop Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:44 volumio volumio[824]: verbose: UNSET VOLATILE Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:44 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 25 12:58:44 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:44 volumio volumio[824]: info: [1761397124934] ControllerCalmRadio::clearAddPlayTrack Oct 25 12:58:44 volumio volumio[824]: { Oct 25 12:58:44 volumio volumio[824]: uri: 'calmradio://6/374', Oct 25 12:58:44 volumio volumio[824]: service: 'calmradio', Oct 25 12:58:44 volumio volumio[824]: trackType: 'CalmRadio', Oct 25 12:58:44 volumio volumio[824]: radioType: 'web', Oct 25 12:58:44 volumio volumio[824]: name: "BILLBOARD TOP 2000's\t", Oct 25 12:58:44 volumio volumio[824]: title: "BILLBOARD TOP 2000's\t", Oct 25 12:58:44 volumio volumio[824]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/374/089e86ceaddfaa1e86dee1f523f8a0da4c21262022e0bfc2643317658090.png', Oct 25 12:58:44 volumio volumio[824]: type: 'webradio', Oct 25 12:58:44 volumio volumio[824]: samplerate: '', Oct 25 12:58:44 volumio volumio[824]: bitdepth: 0, Oct 25 12:58:44 volumio volumio[824]: channels: 0 Oct 25 12:58:44 volumio volumio[824]: } Oct 25 12:58:44 volumio volumio[824]: info: Calm Radio getStreamUrl for Cat 6 Chan 374 Oct 25 12:58:45 volumio volumio[824]: STREAMURL: https://streams.calmradio.com/api/659/128/stream Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand stop took 273 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand stop took 6 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 28 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand clear took 27 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 27 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/659/128/stream" Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 45ms Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: error: updateQueue error: null Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 25ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand addid "https://streams.calmradio.com/api/659/128/stream" took 23 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 5ms Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 12:58:45 volumio volumio[824]: verbose: MPD COMMAND [object Object] Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 54ms Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: info: touch_display: Setting screensaver timeout to 510 seconds. Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 11ms Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 7ms Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand play Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 4ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand play took 2 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 2ms Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 21 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 20 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 2 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: 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":"BILLBOARD TOP 2000's ","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus stop Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: 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":"BILLBOARD TOP 2000's ","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 65ms Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 64ms Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 1 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: 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":"BILLBOARD TOP 2000's ","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 69ms Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: 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":"BILLBOARD TOP 2000's ","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 56ms Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 5ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 6 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 5ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 5 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"CALMRADIO.COM - Billboard Top 2000’s","album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"CALMRADIO.COM - Billboard Top 2000’s","album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 50ms Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 50ms Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 25 12:58:45 volumio volumio[824]: info: Ignoring MPD Status Update Oct 25 12:58:45 volumio volumio[824]: info: Oct 25 12:58:45 volumio volumio[824]: ---------------------------- MPD announces state update: player Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::getState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand status Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 48ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 47 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 76ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand status took 75 milliseconds Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 73 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseState Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My Chemical Romance - The Black Parade - Famous Last Words","artist":"CALMRADIO.COM - Billboard Top 2000’s","album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 140ms Oct 25 12:58:45 volumio volumio[824]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 25 12:58:45 volumio volumio[824]: verbose: ControllerMpd::parseTrackInfo Oct 25 12:58:45 volumio volumio[824]: info: ControllerMpd::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::servicePushState Oct 25 12:58:45 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 25 12:58:45 volumio volumio[824]: verbose: STATE SERVICE {"status":"play","position":0,"seek":444,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My Chemical Romance - The Black Parade - Famous Last Words","artist":"CALMRADIO.COM - Billboard Top 2000’s","album":null,"uri":"https://streams.calmradio.com/api/659/128/stream","trackType":""} Oct 25 12:58:45 volumio volumio[824]: verbose: CURRENT POSITION 0 Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState stateService play Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::syncState currentStatus play Oct 25 12:58:45 volumio volumio[824]: info: Received an update from plugin. extracting info from payload Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: CoreStateMachine::pushState Oct 25 12:58:45 volumio volumio[824]: info: CoreCommandRouter::volumioPushState Oct 25 12:58:45 volumio volumio[824]: info: ------------------------------ 114ms Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:45 volumio volumio[824]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 25 12:58:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 12:58:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 12:58:52 volumio volumio[824]: info: Discovery: Getting this device information Oct 25 12:58:52 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 25 12:58:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 12:59:03 volumio volumio[824]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 12:59:03 volumio volumio[824]: TypeError: Cannot read property 'slice' of null Oct 25 12:59:03 volumio volumio[824]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) Oct 25 12:59:03 volumio volumio[824]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) Oct 25 12:59:03 volumio volumio[824]: at Object.onceWrapper (events.js:422:26) Oct 25 12:59:03 volumio volumio[824]: at ClientRequest.emit (events.js:315:20) Oct 25 12:59:03 volumio volumio[824]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Oct 25 12:59:03 volumio volumio[824]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Oct 25 12:59:03 volumio volumio[824]: at HTTPParser.execute () Oct 25 12:59:03 volumio volumio[824]: at TLSSocket.socketOnData (_http_client.js:509:22) Oct 25 12:59:03 volumio volumio[824]: at TLSSocket.emit (events.js:315:20) Oct 25 12:59:03 volumio volumio[824]: at addChunk (internal/streams/readable.js:309:12) Oct 25 12:59:03 volumio volumio[824]: at readableAddChunk (internal/streams/readable.js:284:9) Oct 25 12:59:03 volumio volumio[824]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Oct 25 12:59:03 volumio volumio[824]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 25 12:59:03 volumio volumio[824]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 12:59:03 volumio sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 12:58 Oct 25 12:59:03 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"