Jun 21 10:08:00 volumio volumio[1247]: info: Jun 21 10:08:00 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:00 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:00 volumio volumio[1247]: info: Jun 21 10:08:00 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:00 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:00 volumio volumio[1247]: info: Jun 21 10:08:00 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:00 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:00 volumio volumio[1247]: info: Jun 21 10:08:00 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:00 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:00 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:08:00 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:00 volumio volumio[1247]: info: ------------------------------ 2ms Jun 21 10:08:00 volumio volumio[1247]: info: sendMpdCommand status took 3 milliseconds Jun 21 10:08:00 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:00 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:00 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:00 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eddie Harris - Listen Here","artist":"Jazz24","album":null,"uri":"https://live.amperwave.net/direct/ppm-jazz24mp3-ibc1","trackType":""} Jun 21 10:08:00 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:00 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:00 volumio volumio[1247]: info: ------------------------------ 18ms Jun 21 10:08:00 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 21 10:08:00 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:00 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:00 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:00 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Eddie Harris - Listen Here","artist":"Jazz24","album":null,"uri":"https://live.amperwave.net/direct/ppm-jazz24mp3-ibc1","trackType":""} Jun 21 10:08:00 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:00 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:00 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:00 volumio volumio[1247]: info: ------------------------------ 27ms Jun 21 10:08:01 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 21 10:08:01 volumio dbus-daemon[701]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.399' (uid=0 pid=28520 comm="timedatectl show --property=NTPSynchronized --valu") Jun 21 10:08:01 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 21 10:08:01 volumio dbus-daemon[701]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 21 10:08:01 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 21 10:08:01 volumio setdatetime-helper.sh[28519]: Time is already synchronized. Jun 21 10:08:01 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 21 10:08:01 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 21 10:08:05 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:08:05 volumio volumio[1247]: info: [1750475285552] ControllerWebradio::stop Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:08:05 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:05 volumio volumio[1247]: info: Adding Item to queue: http://kdhx-ice.streamguys1.com:80/live Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:05 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:05 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:08:05 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:05 volumio volumio[1247]: info: [1750475285568] ControllerWebradio::clearAddPlayTrack Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:05 volumio volumio[1247]: info: Jun 21 10:08:05 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:05 volumio volumio[1247]: info: sendMpdCommand stop took 22 milliseconds Jun 21 10:08:05 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:05 volumio volumio[1247]: info: sendMpdCommand stop took 7 milliseconds Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:08:05 volumio volumio[1247]: info: Jun 21 10:08:05 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:05 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:05 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:05 volumio volumio[1247]: info: sendMpdCommand clear took 2 milliseconds Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://kdhx-ice.streamguys1.com:80/live" Jun 21 10:08:05 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:08:05 volumio volumio[1247]: info: ------------------------------ 16ms Jun 21 10:08:05 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 21 10:08:05 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:05 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:08:05 volumio volumio[1247]: info: ------------------------------ 20ms Jun 21 10:08:05 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" took 1 milliseconds Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:08:06 volumio volumio[1247]: info: Jun 21 10:08:06 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:06 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:08:06 volumio volumio[1247]: info: ------------------------------ 2ms Jun 21 10:08:06 volumio volumio[1247]: info: Jun 21 10:08:06 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:06 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:06 volumio volumio[1247]: info: Jun 21 10:08:06 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:06 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand status took 3 milliseconds Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand status took 5 milliseconds Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand clearerror took 3 milliseconds Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:06 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:06 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:06 volumio volumio[1247]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Jun 21 10:08:06 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::syncState stateService stop Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:06 volumio volumio[1247]: info: No code Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:06 volumio volumio[1247]: info: ------------------------------ 20ms Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand clearerror took 19 milliseconds Jun 21 10:08:06 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 21 10:08:06 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:06 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:06 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:06 volumio volumio[1247]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Jun 21 10:08:06 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::syncState stateService stop Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:06 volumio volumio[1247]: info: No code Jun 21 10:08:06 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:06 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:06 volumio volumio[1247]: info: ------------------------------ 34ms Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 10:08:08 volumio volumio[1247]: info: Discovery: Getting this device information Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 10:08:08 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:08:08 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:08 volumio volumio[1247]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:08 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:08:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:08 volumio volumio[1247]: info: [1750475288400] ControllerWebradio::clearAddPlayTrack Jun 21 10:08:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:08 volumio volumio[1247]: info: sendMpdCommand stop took 2 milliseconds Jun 21 10:08:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:08:08 volumio volumio[1247]: info: Jun 21 10:08:08 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:08 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:08 volumio volumio[1247]: info: sendMpdCommand clear took 0 milliseconds Jun 21 10:08:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Jun 21 10:08:08 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:08:08 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:10 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Jun 21 10:08:10 volumio volumio[1247]: info: Jun 21 10:08:10 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:10 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:10 volumio volumio[1247]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 1 milliseconds Jun 21 10:08:10 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:08:10 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:08:10 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:10 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:08:12 volumio volumio[1247]: info: Jun 21 10:08:12 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:12 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:12 volumio volumio[1247]: info: Jun 21 10:08:12 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:12 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:12 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:12 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:08:12 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:12 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:12 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:12 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Jun 21 10:08:12 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:12 volumio volumio[1247]: info: ------------------------------ 6ms Jun 21 10:08:12 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:12 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:12 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:12 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kexp64.aac","artist":null,"album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Jun 21 10:08:12 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:12 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:12 volumio volumio[1247]: info: ------------------------------ 13ms Jun 21 10:08:12 volumio volumio[1247]: info: Jun 21 10:08:12 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:12 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:12 volumio volumio[1247]: info: Jun 21 10:08:12 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:12 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:12 volumio volumio[1247]: info: ------------------------------ 4ms Jun 21 10:08:12 volumio volumio[1247]: info: sendMpdCommand status took 3 milliseconds Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:12 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 21 10:08:12 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:12 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:12 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:12 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"kexp64.aac","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Jun 21 10:08:12 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:12 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:12 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:12 volumio volumio[1247]: info: ------------------------------ 15ms Jun 21 10:08:17 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:08:17 volumio volumio[1247]: info: [1750475297266] ControllerWebradio::stop Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:08:17 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:17 volumio volumio[1247]: info: Adding Item to queue: http://stream1.opb.org/kmhd.mp3 Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:17 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:08:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:17 volumio volumio[1247]: info: [1750475297284] ControllerWebradio::clearAddPlayTrack Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:17 volumio volumio[1247]: info: Jun 21 10:08:17 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:17 volumio volumio[1247]: info: sendMpdCommand stop took 25 milliseconds Jun 21 10:08:17 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:17 volumio volumio[1247]: info: sendMpdCommand stop took 8 milliseconds Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:08:17 volumio volumio[1247]: info: Jun 21 10:08:17 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:17 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:17 volumio volumio[1247]: info: sendMpdCommand status took 20 milliseconds Jun 21 10:08:17 volumio volumio[1247]: info: sendMpdCommand clear took 20 milliseconds Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://stream1.opb.org/kmhd.mp3" Jun 21 10:08:17 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:08:18 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:08:18 volumio volumio[1247]: info: ------------------------------ 1615ms Jun 21 10:08:18 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1613 milliseconds Jun 21 10:08:18 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://stream1.opb.org/kmhd.mp3" Jun 21 10:08:18 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:18 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:08:18 volumio volumio[1247]: info: ------------------------------ 1641ms Jun 21 10:08:18 volumio volumio[1247]: info: Jun 21 10:08:18 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:18 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:18 volumio volumio[1247]: info: sendMpdCommand add "http://stream1.opb.org/kmhd.mp3" took 1 milliseconds Jun 21 10:08:18 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:08:18 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:08:18 volumio volumio[1247]: info: ------------------------------ 2ms Jun 21 10:08:18 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:08:20 volumio volumio[1247]: info: Jun 21 10:08:20 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:20 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:20 volumio volumio[1247]: info: Jun 21 10:08:20 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:20 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:20 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:20 volumio volumio[1247]: info: Jun 21 10:08:20 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:20 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:20 volumio volumio[1247]: info: Jun 21 10:08:20 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:20 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:20 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:08:20 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:20 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:20 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:20 volumio volumio[1247]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 21 10:08:20 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::syncState stateService stop Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:20 volumio volumio[1247]: info: No code Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:20 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:08:20 volumio volumio[1247]: info: ------------------------------ 12ms Jun 21 10:08:20 volumio volumio[1247]: info: ------------------------------ 14ms Jun 21 10:08:20 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:08:20 volumio volumio[1247]: info: ------------------------------ 11ms Jun 21 10:08:20 volumio volumio[1247]: info: sendMpdCommand status took 11 milliseconds Jun 21 10:08:20 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:20 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:20 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:20 volumio volumio[1247]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 21 10:08:20 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::syncState stateService stop Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:20 volumio volumio[1247]: info: No code Jun 21 10:08:20 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:20 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:20 volumio volumio[1247]: info: ------------------------------ 21ms Jun 21 10:08:27 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:27 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:27 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:08:27 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:27 volumio volumio[1247]: info: Adding Item to queue: https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream Jun 21 10:08:27 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:08:27 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:27 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:27 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:08:27 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:27 volumio volumio[1247]: info: [1750475307468] ControllerWebradio::clearAddPlayTrack Jun 21 10:08:27 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:27 volumio volumio[1247]: info: sendMpdCommand stop took 1 milliseconds Jun 21 10:08:27 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:08:27 volumio volumio[1247]: info: Jun 21 10:08:27 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:27 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:27 volumio volumio[1247]: info: sendMpdCommand clear took 1 milliseconds Jun 21 10:08:27 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" Jun 21 10:08:27 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:08:27 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:28 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" Jun 21 10:08:28 volumio volumio[1247]: info: sendMpdCommand add "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" took 0 milliseconds Jun 21 10:08:28 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:08:28 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:08:28 volumio volumio[1247]: info: Jun 21 10:08:28 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:28 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:28 volumio volumio[1247]: info: sendMpdCommand play took 3 milliseconds Jun 21 10:08:28 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 10:08:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 10:08:29 volumio volumio[1247]: info: Discovery: Getting this device information Jun 21 10:08:29 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 21 10:08:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 10:08:30 volumio volumio[1247]: info: Jun 21 10:08:30 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:30 volumio volumio[1247]: info: Jun 21 10:08:30 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:30 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:30 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Jun 21 10:08:30 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:30 volumio volumio[1247]: info: ------------------------------ 4ms Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:30 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:30 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Jun 21 10:08:30 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:30 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:30 volumio volumio[1247]: info: ------------------------------ 8ms Jun 21 10:08:30 volumio volumio[1247]: info: Jun 21 10:08:30 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:30 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:30 volumio volumio[1247]: info: Jun 21 10:08:30 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:30 volumio volumio[1247]: info: Jun 21 10:08:30 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:30 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:30 volumio volumio[1247]: info: Jun 21 10:08:30 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:30 volumio volumio[1247]: info: ------------------------------ 2ms Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:30 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 21 10:08:30 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:30 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:30 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:30 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Saint Motel - My Type","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Jun 21 10:08:30 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:30 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:30 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:30 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:30 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Saint Motel - My Type","artist":"Beaumont Stream","album":null,"uri":"https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream","trackType":""} Jun 21 10:08:30 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:30 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:30 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:30 volumio volumio[1247]: info: ------------------------------ 16ms Jun 21 10:08:30 volumio volumio[1247]: info: ------------------------------ 16ms Jun 21 10:08:32 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 21 10:08:36 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:08:36 volumio volumio[1247]: info: [1750475316193] ControllerWebradio::stop Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:08:36 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:08:36 volumio volumio[1247]: info: Adding Item to queue: http://mscp3.live-streams.nl:8360/flac.flac Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:08:36 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:36 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:08:36 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:36 volumio volumio[1247]: info: [1750475316207] ControllerWebradio::clearAddPlayTrack Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:08:36 volumio volumio[1247]: info: Jun 21 10:08:36 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:36 volumio volumio[1247]: info: sendMpdCommand stop took 32 milliseconds Jun 21 10:08:36 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:36 volumio volumio[1247]: info: sendMpdCommand stop took 17 milliseconds Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:08:36 volumio volumio[1247]: info: Jun 21 10:08:36 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:36 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:36 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:36 volumio volumio[1247]: info: sendMpdCommand clear took 2 milliseconds Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Jun 21 10:08:36 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:08:36 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:08:36 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 21 10:08:36 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:36 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:08:36 volumio volumio[1247]: info: ------------------------------ 8ms Jun 21 10:08:36 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Jun 21 10:08:37 volumio volumio[1247]: info: Jun 21 10:08:37 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:37 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:37 volumio volumio[1247]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 0 milliseconds Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:08:37 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:37 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:08:37 volumio volumio[1247]: info: Jun 21 10:08:37 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:37 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:37 volumio volumio[1247]: info: Jun 21 10:08:37 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:37 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:08:37 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:37 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:08:37 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:37 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:37 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:37 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:37 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 21 10:08:37 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:08:37 volumio volumio[1247]: info: ------------------------------ 4ms Jun 21 10:08:37 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:08:37 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:37 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:37 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:37 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:37 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 21 10:08:37 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:37 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:37 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:37 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:37 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:37 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:37 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:37 volumio volumio[1247]: info: ------------------------------ 10ms Jun 21 10:08:38 volumio volumio[1247]: info: Jun 21 10:08:38 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:08:38 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:08:38 volumio volumio[1247]: info: Jun 21 10:08:38 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:08:38 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:08:38 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:08:38 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:08:38 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:08:38 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:08:38 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:08:38 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 21 10:08:38 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:08:38 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:08:38 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:08:38 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:38 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 21 10:08:38 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:08:38 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:08:38 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:08:38 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:08:38 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:38 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:08:38 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:08:38 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:38 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:08:38 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:08:38 volumio volumio[1247]: info: ------------------------------ 9ms Jun 21 10:08:56 volumio volumio[1247]: info: Saving local item radio-favourites Jun 21 10:08:56 volumio volumio[1247]: info: Pushing Favourites {"uri":"http://mscp3.live-streams.nl:8360/flac.flac","favourite":false} Jun 21 10:08:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 10:08:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 10:08:59 volumio volumio[1247]: info: Discovery: Getting this device information Jun 21 10:08:59 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 21 10:08:59 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 10:09:07 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:09:07 volumio volumio[1247]: info: [1750475347727] ControllerWebradio::stop Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:09:07 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:07 volumio volumio[1247]: info: Adding Item to queue: http://stream-relay-geo.ntslive.net/stream2 Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:07 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:07 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:09:07 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:07 volumio volumio[1247]: info: [1750475347737] ControllerWebradio::clearAddPlayTrack Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:07 volumio volumio[1247]: info: Jun 21 10:09:07 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:07 volumio volumio[1247]: info: sendMpdCommand stop took 14 milliseconds Jun 21 10:09:07 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:07 volumio volumio[1247]: info: sendMpdCommand stop took 4 milliseconds Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:09:07 volumio volumio[1247]: info: Jun 21 10:09:07 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:07 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:07 volumio volumio[1247]: info: sendMpdCommand status took 13 milliseconds Jun 21 10:09:07 volumio volumio[1247]: info: sendMpdCommand clear took 13 milliseconds Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:07 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://stream-relay-geo.ntslive.net/stream2" Jun 21 10:09:07 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:09:07 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:09:07 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 538 milliseconds Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:08 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:09:08 volumio volumio[1247]: info: ------------------------------ 553ms Jun 21 10:09:08 volumio volumio[1247]: info: Jun 21 10:09:08 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:08 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" took 1 milliseconds Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:09:08 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:09:08 volumio volumio[1247]: info: Jun 21 10:09:08 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:08 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:08 volumio volumio[1247]: info: Jun 21 10:09:08 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand status took 3 milliseconds Jun 21 10:09:08 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:08 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:08 volumio volumio[1247]: 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":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Jun 21 10:09:08 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:09:08 volumio volumio[1247]: info: ------------------------------ 8ms Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:08 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:08 volumio volumio[1247]: 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":"stream2","artist":null,"album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Jun 21 10:09:08 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:08 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:08 volumio volumio[1247]: info: ------------------------------ 11ms Jun 21 10:09:08 volumio volumio[1247]: info: Jun 21 10:09:08 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:08 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:08 volumio volumio[1247]: info: Jun 21 10:09:08 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:08 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:08 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand status took 3 milliseconds Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:08 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:08 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:08 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:08 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:08 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":" - ","artist":"NTS Radio","album":null,"uri":"http://stream-relay-geo.ntslive.net/stream2","trackType":""} Jun 21 10:09:08 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:08 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:08 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:08 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:09 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:09 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:09 volumio volumio[1247]: info: ------------------------------ 12ms Jun 21 10:09:13 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:09:13 volumio volumio[1247]: info: [1750475353154] ControllerWebradio::stop Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:09:13 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:13 volumio volumio[1247]: info: Adding Item to queue: http://stream.radioparadise.com/flacm Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: info: [1750475353163] ControllerWebradio::clearAddPlayTrack Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand stop took 13 milliseconds Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand stop took 3 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:13 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand clear took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" Jun 21 10:09:13 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:09:13 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 100ms Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 99 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:13 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 102ms Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:13 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 2ms Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand status took 0 milliseconds Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Jun 21 10:09:13 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Jun 21 10:09:13 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:13 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 9ms Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:13 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:13 volumio volumio[1247]: info: Jun 21 10:09:13 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 2ms Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:13 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:13 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:13 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} Jun 21 10:09:13 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:13 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:13 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:13 volumio volumio[1247]: info: ------------------------------ 12ms Jun 21 10:09:16 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:16 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:09:16 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:09:16 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:16 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:16 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:09:16 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:16 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:16 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:16 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:16 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:16 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:09:17 volumio volumio[1247]: info: [1750475357000] ControllerWebradio::stop Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:09:17 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:17 volumio volumio[1247]: info: Adding Item to queue: http://stream.radioparadise.com/rock-flacm Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: info: [1750475357010] ControllerWebradio::clearAddPlayTrack Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand stop took 19 milliseconds Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand stop took 9 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:17 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand clear took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm" Jun 21 10:09:17 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 12ms Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:17 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 15ms Jun 21 10:09:17 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:17 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand status took 0 milliseconds Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Jun 21 10:09:17 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 5ms Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Jun 21 10:09:17 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:17 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 12ms Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:17 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:17 volumio volumio[1247]: info: Jun 21 10:09:17 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:17 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:17 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:17 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Jun 21 10:09:17 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:17 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:17 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:17 volumio volumio[1247]: info: ------------------------------ 11ms Jun 21 10:09:19 volumio volumio[1247]: info: Jun 21 10:09:19 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:19 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:19 volumio volumio[1247]: info: Jun 21 10:09:19 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:19 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:19 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:19 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:09:19 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:19 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:19 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:19 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:19 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:19 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:19 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:19 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:19 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2957,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"James Gang - The Bomber","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Jun 21 10:09:19 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:19 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:19 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:19 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:19 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:19 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:19 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:19 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:19 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:19 volumio volumio[1247]: info: ------------------------------ 11ms Jun 21 10:09:23 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::ClearQueue Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::stPlaybackTimer Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::serviceStop Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::serviceStop Jun 21 10:09:23 volumio volumio[1247]: info: [1750475363820] ControllerWebradio::stop Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::clearPlayQueue Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::addQueueItems Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::addQueueItems Jun 21 10:09:23 volumio volumio[1247]: info: Preload queue cleared Jun 21 10:09:23 volumio volumio[1247]: info: Adding Item to queue: http://listen.jazz88.org/ksds.mp3 Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::volumioPushQueue Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::saveQueue Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::updateTrackBlock Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::getTrackBlock Jun 21 10:09:23 volumio volumio[1247]: info: CoreCommandRouter::volumioPlay Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::play index 0 Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::stop Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::play index undefined Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:23 volumio volumio[1247]: info: CoreStateMachine::startPlaybackTimer Jun 21 10:09:23 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:23 volumio volumio[1247]: info: [1750475363832] ControllerWebradio::clearAddPlayTrack Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 10:09:23 volumio volumio[1247]: info: Jun 21 10:09:23 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:23 volumio volumio[1247]: info: sendMpdCommand stop took 15 milliseconds Jun 21 10:09:23 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:23 volumio volumio[1247]: info: sendMpdCommand stop took 3 milliseconds Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 10:09:23 volumio volumio[1247]: info: Jun 21 10:09:23 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:23 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:23 volumio volumio[1247]: info: sendMpdCommand status took 11 milliseconds Jun 21 10:09:23 volumio volumio[1247]: info: sendMpdCommand clear took 11 milliseconds Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:23 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" Jun 21 10:09:23 volumio volumio[1247]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 21 10:09:24 volumio volumio[1247]: error: updateQueue error: null Jun 21 10:09:24 volumio volumio[1247]: info: ------------------------------ 978ms Jun 21 10:09:24 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 977 milliseconds Jun 21 10:09:24 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" Jun 21 10:09:24 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:24 volumio volumio[1247]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 21 10:09:24 volumio volumio[1247]: info: ------------------------------ 990ms Jun 21 10:09:24 volumio volumio[1247]: info: Jun 21 10:09:24 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:24 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:24 volumio volumio[1247]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 2 milliseconds Jun 21 10:09:24 volumio volumio[1247]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 10:09:24 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand play Jun 21 10:09:24 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:09:24 volumio volumio[1247]: info: sendMpdCommand play took 1 milliseconds Jun 21 10:09:25 volumio volumio[1247]: info: Jun 21 10:09:25 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:25 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:25 volumio volumio[1247]: info: Jun 21 10:09:25 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:25 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:25 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:25 volumio volumio[1247]: info: sendMpdCommand status took 1 milliseconds Jun 21 10:09:25 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:25 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:25 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:25 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 21 10:09:25 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus stop Jun 21 10:09:25 volumio volumio[1247]: info: ------------------------------ 3ms Jun 21 10:09:25 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:25 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:25 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:25 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 21 10:09:25 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:25 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:25 volumio volumio[1247]: info: ------------------------------ 9ms Jun 21 10:09:25 volumio volumio[1247]: info: Jun 21 10:09:25 volumio volumio[1247]: ---------------------------- MPD announces system playlist update Jun 21 10:09:25 volumio volumio[1247]: info: Ignoring MPD Status Update Jun 21 10:09:25 volumio volumio[1247]: info: Jun 21 10:09:25 volumio volumio[1247]: ---------------------------- MPD announces state update: player Jun 21 10:09:25 volumio volumio[1247]: info: ControllerMpd::getState Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand status Jun 21 10:09:25 volumio volumio[1247]: info: ------------------------------ 1ms Jun 21 10:09:25 volumio volumio[1247]: info: sendMpdCommand status took 2 milliseconds Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::parseState Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 10:09:25 volumio volumio[1247]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 21 10:09:25 volumio volumio[1247]: verbose: ControllerMpd::parseTrackInfo Jun 21 10:09:25 volumio volumio[1247]: info: ControllerMpd::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::servicePushState Jun 21 10:09:25 volumio volumio[1247]: info: CorePlayQueue::getTrack 0 Jun 21 10:09:25 volumio volumio[1247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":975,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Jun 21 10:09:25 volumio volumio[1247]: verbose: CURRENT POSITION 0 Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::syncState stateService play Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::syncState currentStatus play Jun 21 10:09:25 volumio volumio[1247]: info: Received an update from plugin. extracting info from payload Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreStateMachine::pushState Jun 21 10:09:25 volumio volumio[1247]: info: CoreCommandRouter::volumioPushState Jun 21 10:09:25 volumio volumio[1247]: info: ------------------------------ 10ms Jun 21 10:09:29 volumio volumio[1247]: Searching plugin music_service/webradio Jun 21 10:09:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 21 10:09:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 10:09:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 10:09:29 volumio volumio[1247]: info: Discovery: Getting this device information Jun 21 10:09:29 volumio volumio[1247]: info: CoreCommandRouter::volumioGetState Jun 21 10:09:29 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 10:09:30 volumio volumio[1247]: Searching plugin music_service/webradio Jun 21 10:09:30 volumio volumio[1247]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 21 10:09:30 volumio volumio[1247]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 10:09:30 volumio volumio[1247]: TypeError: Cannot read properties of undefined (reading 'length') Jun 21 10:09:30 volumio volumio[1247]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jun 21 10:09:30 volumio volumio[1247]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 21 10:09:30 volumio volumio[1247]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 21 10:09:30 volumio volumio[1247]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 21 10:09:30 volumio volumio[1247]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 10:09:31 volumio sudo[28699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-21 10:08' Jun 21 10:09:31 volumio sudo[28699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"