May 29 09:57:07 volumio-alpha wpa_supplicant[1198]: RRM: Ignoring radio measurement request: Not RRM network May 29 09:57:07 volumio-alpha systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 09:57:07 volumio-alpha dbus-daemon[842]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.416' (uid=0 pid=29656 comm="timedatectl show --property=NTPSynchronized --valu") May 29 09:57:07 volumio-alpha systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 09:57:08 volumio-alpha dbus-daemon[842]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 09:57:08 volumio-alpha systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 09:57:08 volumio-alpha setdatetime-helper.sh[29655]: Time is already synchronized. May 29 09:57:08 volumio-alpha systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 09:57:08 volumio-alpha systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 09:57:14 volumio-alpha wpa_supplicant[1198]: RRM: Ignoring radio measurement request: Not RRM network May 29 09:57:18 volumio-alpha go-librespot[1649]: time="2025-05-29T09:57:18+01:00" level=trace msg="sent dealer ping" May 29 09:57:18 volumio-alpha go-librespot[1649]: time="2025-05-29T09:57:18+01:00" level=trace msg="received dealer pong" May 29 09:57:23 volumio-alpha volumio[1392]: info: May 29 09:57:23 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:57:23 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:57:23 volumio-alpha volumio[1392]: info: May 29 09:57:23 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:57:23 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:57:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:57:23 volumio-alpha volumio[1392]: info: ------------------------------ 0ms May 29 09:57:23 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:57:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:57:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:57:23 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:57:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:57:23 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:57:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:57:23 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":896858,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"A Retro Ad Break - A Blast from the Past","artist":"BritCom 1 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:57:23 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:57:23 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:57:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:57:23 volumio-alpha volumio[1392]: info: ------------------------------ 3ms May 29 09:57:27 volumio-alpha volumio[1392]: info: May 29 09:57:27 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:57:27 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:57:27 volumio-alpha volumio[1392]: info: May 29 09:57:27 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:57:27 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:57:27 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:57:27 volumio-alpha volumio[1392]: info: ------------------------------ 0ms May 29 09:57:27 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:57:27 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:57:27 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:57:27 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 09:57:27 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:57:27 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:57:27 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:57:27 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":900983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Warner Mack - Roc-A-Chicka","artist":"BritCom 1 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:57:27 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:57:27 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:57:27 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:57:27 volumio-alpha volumio[1392]: info: ------------------------------ 3ms May 29 09:57:36 volumio-alpha wpa_supplicant[1198]: RRM: Ignoring radio measurement request: Not RRM network May 29 09:57:38 volumio-alpha systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 09:57:48 volumio-alpha go-librespot[1649]: time="2025-05-29T09:57:48+01:00" level=trace msg="sent dealer ping" May 29 09:57:48 volumio-alpha go-librespot[1649]: time="2025-05-29T09:57:48+01:00" level=trace msg="received dealer pong" May 29 09:57:55 volumio-alpha wpa_supplicant[1198]: RRM: Ignoring radio measurement request: Not RRM network May 29 09:58:12 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::stPlaybackTimer May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::serviceStop May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::serviceStop May 29 09:58:12 volumio-alpha volumio[1392]: info: [1748509092167] ControllerWebradio::stop May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:12 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:12 volumio-alpha volumio[1392]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s282318&filter=s:g209:country May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 14 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: 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":"Warner Mack - Roc-A-Chicka","artist":"BritCom 1 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:58:12 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService stop May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus stop May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: No code May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 5ms May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: [1748509092405] ControllerWebradio::clearAddPlayTrack May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 1 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:12 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 0 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "http://65.21.202.84:8022/listen.pls?sid=1" May 29 09:58:12 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:12 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 137ms May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand load "http://65.21.202.84:8022/listen.pls?sid=1" took 137 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand play took 1 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand status took 4 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand status took 2 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BritCom 2 - British Comedy Radio (Pumpkin FM OTRN)","artist":null,"album":null,"uri":"http://65.21.202.84:8022/stream","trackType":""} May 29 09:58:12 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus stop May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 7ms May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BritCom 2 - British Comedy Radio (Pumpkin FM OTRN)","artist":null,"album":null,"uri":"http://65.21.202.84:8022/stream","trackType":""} May 29 09:58:12 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:12 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 6ms May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:12 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:12 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:12 volumio-alpha volumio[1392]: info: May 29 09:58:12 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand status took 1 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Retro British Adverts - Weetabix (2nd Version)","artist":"BritCom 2 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8022/stream","trackType":""} May 29 09:58:12 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:12 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 4ms May 29 09:58:12 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 09:58:12 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:12 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:12 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Retro British Adverts - Weetabix (2nd Version)","artist":"BritCom 2 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8022/stream","trackType":""} May 29 09:58:12 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:12 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:12 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:12 volumio-alpha volumio[1392]: info: ------------------------------ 6ms May 29 09:58:15 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::stPlaybackTimer May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::serviceStop May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::serviceStop May 29 09:58:15 volumio-alpha volumio[1392]: info: [1748509095631] ControllerWebradio::stop May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:15 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:15 volumio-alpha volumio[1392]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564585 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: [1748509095634] ControllerWebradio::clearAddPlayTrack May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 5 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: info: May 29 09:58:15 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:15 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 2 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:15 volumio-alpha volumio[1392]: info: May 29 09:58:15 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:15 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 1 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564585" May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:15 volumio-alpha volumio[1392]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 29 09:58:15 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:15 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:15 volumio-alpha volumio[1392]: info: ------------------------------ 0ms May 29 09:58:15 volumio-alpha volumio[1392]: info: May 29 09:58:15 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:15 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564585" took 170 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:15 volumio-alpha volumio[1392]: info: ------------------------------ 0ms May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand play took 0 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: info: May 29 09:58:15 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:15 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:15 volumio-alpha volumio[1392]: info: May 29 09:58:15 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand status took 24 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:15 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 97/250) BritCom 3 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8200/stream","trackType":""} May 29 09:58:15 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus stop May 29 09:58:15 volumio-alpha volumio[1392]: info: ------------------------------ 26ms May 29 09:58:15 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 09:58:15 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:15 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 97/250) BritCom 3 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8200/stream","trackType":""} May 29 09:58:15 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:15 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:15 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:15 volumio-alpha volumio[1392]: info: ------------------------------ 4ms May 29 09:58:16 volumio-alpha volumio[1392]: info: May 29 09:58:16 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:16 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:16 volumio-alpha volumio[1392]: info: May 29 09:58:16 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:16 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:16 volumio-alpha volumio[1392]: info: May 29 09:58:16 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:16 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:16 volumio-alpha volumio[1392]: info: May 29 09:58:16 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:16 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:16 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:16 volumio-alpha volumio[1392]: info: sendMpdCommand status took 1 milliseconds May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:16 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:16 volumio-alpha volumio[1392]: info: sendMpdCommand status took 1 milliseconds May 29 09:58:16 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:16 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:16 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Perry Como - Mandolins In The Moonlight","artist":"BritCom 3 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8200/stream","trackType":""} May 29 09:58:16 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:16 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:16 volumio-alpha volumio[1392]: info: ------------------------------ 5ms May 29 09:58:16 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 3 milliseconds May 29 09:58:16 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:16 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:16 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Perry Como - Mandolins In The Moonlight","artist":"BritCom 3 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8200/stream","trackType":""} May 29 09:58:16 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:16 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:16 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:16 volumio-alpha volumio[1392]: info: ------------------------------ 7ms May 29 09:58:18 volumio-alpha go-librespot[1649]: time="2025-05-29T09:58:18+01:00" level=trace msg="sent dealer ping" May 29 09:58:18 volumio-alpha go-librespot[1649]: time="2025-05-29T09:58:18+01:00" level=trace msg="received dealer pong" May 29 09:58:19 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::stPlaybackTimer May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::serviceStop May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::serviceStop May 29 09:58:19 volumio-alpha volumio[1392]: info: [1748509099385] ControllerWebradio::stop May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:19 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:19 volumio-alpha volumio[1392]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s285581&filter=s:g209:country May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:19 volumio-alpha volumio[1392]: info: May 29 09:58:19 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:19 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 13 milliseconds May 29 09:58:19 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:19 volumio-alpha volumio[1392]: info: sendMpdCommand status took 1 milliseconds May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:19 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:19 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: 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":"Perry Como - Mandolins In The Moonlight","artist":"BritCom 3 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"http://65.21.202.84:8200/stream","trackType":""} May 29 09:58:19 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService stop May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus stop May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:19 volumio-alpha volumio[1392]: info: No code May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:19 volumio-alpha volumio[1392]: info: ------------------------------ 5ms May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:19 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:19 volumio-alpha volumio[1392]: info: [1748509099616] ControllerWebradio::clearAddPlayTrack May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:19 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 1 milliseconds May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:19 volumio-alpha volumio[1392]: info: May 29 09:58:19 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:19 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:19 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 0 milliseconds May 29 09:58:19 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "https://cast2.asurahosting.com/proxy/britcom1/stream" May 29 09:58:19 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:19 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand add "https://cast2.asurahosting.com/proxy/britcom1/stream" May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:20 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand add "https://cast2.asurahosting.com/proxy/britcom1/stream" took 0 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand play took 1 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand status took 25 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:20 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:58:20 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus stop May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 26ms May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:20 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:58:20 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:20 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 5ms May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:20 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:20 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:20 volumio-alpha volumio[1392]: info: May 29 09:58:20 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand status took 1 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand status took 0 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:20 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Warner Mack - Roc-A-Chicka","artist":"BritCom 1 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:58:20 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:20 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 4ms May 29 09:58:20 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 3 milliseconds May 29 09:58:20 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:20 volumio-alpha volumio[1392]: info: ControllerMpd::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::servicePushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:20 volumio-alpha volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Warner Mack - Roc-A-Chicka","artist":"BritCom 1 - British Comedy Radio (Pumpkin FM OTRN)","album":null,"uri":"https://cast2.asurahosting.com/proxy/britcom1/stream","trackType":""} May 29 09:58:20 volumio-alpha volumio[1392]: verbose: CURRENT POSITION 0 May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState stateService play May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::syncState currentStatus play May 29 09:58:20 volumio-alpha volumio[1392]: info: Received an update from plugin. extracting info from payload May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:20 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:20 volumio-alpha volumio[1392]: info: ------------------------------ 6ms May 29 09:58:23 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::stPlaybackTimer May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::pushState May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushState May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::serviceStop May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::serviceStop May 29 09:58:23 volumio-alpha volumio[1392]: info: [1748509103820] ControllerWebradio::stop May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:23 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:23 volumio-alpha volumio[1392]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564618 May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:23 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:23 volumio-alpha volumio[1392]: info: [1748509103823] ControllerWebradio::clearAddPlayTrack May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:23 volumio-alpha volumio[1392]: info: May 29 09:58:23 volumio-alpha volumio[1392]: ---------------------------- MPD announces state update: player May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 8 milliseconds May 29 09:58:23 volumio-alpha volumio[1392]: info: ControllerMpd::getState May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand status May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 5 milliseconds May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:23 volumio-alpha volumio[1392]: info: May 29 09:58:23 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:23 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand status took 1 milliseconds May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 0 milliseconds May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseState May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564618" May 29 09:58:23 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:23 volumio-alpha volumio[1392]: info: ------------------------------ 0ms May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::parseTrackInfo May 29 09:58:23 volumio-alpha volumio[1392]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 29 09:58:23 volumio-alpha volumio[1392]: info: ------------------------------ 2ms May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564618" took 79 milliseconds May 29 09:58:23 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:23 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:23 volumio-alpha volumio[1392]: info: sendMpdCommand play took 0 milliseconds May 29 09:58:29 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:29 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:29 volumio-alpha volumio[1392]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564612 May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:29 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:29 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:29 volumio-alpha volumio[1392]: info: [1748509109938] ControllerWebradio::clearAddPlayTrack May 29 09:58:29 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:29 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 1 milliseconds May 29 09:58:29 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:29 volumio-alpha volumio[1392]: info: May 29 09:58:29 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:29 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:29 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 0 milliseconds May 29 09:58:29 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564612" May 29 09:58:29 volumio-alpha systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 09:58:29 volumio-alpha dbus-daemon[842]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.418' (uid=0 pid=29782 comm="timedatectl show --property=NTPSynchronized --valu") May 29 09:58:29 volumio-alpha systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 09:58:30 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:30 volumio-alpha volumio[1392]: info: ------------------------------ 83ms May 29 09:58:30 volumio-alpha volumio[1392]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564612" took 82 milliseconds May 29 09:58:30 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:30 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:30 volumio-alpha volumio[1392]: info: sendMpdCommand play took 0 milliseconds May 29 09:58:30 volumio-alpha dbus-daemon[842]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 09:58:30 volumio-alpha systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 09:58:30 volumio-alpha setdatetime-helper.sh[29781]: Time is already synchronized. May 29 09:58:30 volumio-alpha systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 09:58:30 volumio-alpha systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 09:58:35 volumio-alpha wpa_supplicant[1198]: RRM: Ignoring radio measurement request: Not RRM network May 29 09:58:36 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:36 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:36 volumio-alpha volumio[1392]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564612 May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:36 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:36 volumio-alpha volumio[1392]: info: [1748509116773] ControllerWebradio::clearAddPlayTrack May 29 09:58:36 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:36 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 1 milliseconds May 29 09:58:36 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:36 volumio-alpha volumio[1392]: info: May 29 09:58:36 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:36 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:36 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 0 milliseconds May 29 09:58:36 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564612" May 29 09:58:36 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:36 volumio-alpha volumio[1392]: info: ------------------------------ 1ms May 29 09:58:36 volumio-alpha volumio[1392]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564612" took 80 milliseconds May 29 09:58:36 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:36 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:36 volumio-alpha volumio[1392]: info: sendMpdCommand play took 0 milliseconds May 29 09:58:45 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::ClearQueue May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::clearPlayQueue May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::addQueueItems May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::addQueueItems May 29 09:58:45 volumio-alpha volumio[1392]: info: Preload queue cleared May 29 09:58:45 volumio-alpha volumio[1392]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564618 May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPushQueue May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::saveQueue May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::updateTrackBlock May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrackBlock May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreCommandRouter::volumioPlay May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::play index 0 May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::stop May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::play index undefined May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::startPlaybackTimer May 29 09:58:45 volumio-alpha volumio[1392]: info: CorePlayQueue::getTrack 0 May 29 09:58:45 volumio-alpha volumio[1392]: info: [1748509125320] ControllerWebradio::clearAddPlayTrack May 29 09:58:45 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop May 29 09:58:45 volumio-alpha volumio[1392]: info: sendMpdCommand stop took 1 milliseconds May 29 09:58:45 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear May 29 09:58:45 volumio-alpha volumio[1392]: info: May 29 09:58:45 volumio-alpha volumio[1392]: ---------------------------- MPD announces system playlist update May 29 09:58:45 volumio-alpha volumio[1392]: info: Ignoring MPD Status Update May 29 09:58:45 volumio-alpha volumio[1392]: info: sendMpdCommand clear took 0 milliseconds May 29 09:58:45 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564618" May 29 09:58:45 volumio-alpha volumio[1392]: error: updateQueue error: null May 29 09:58:45 volumio-alpha volumio[1392]: info: ------------------------------ 0ms May 29 09:58:45 volumio-alpha volumio[1392]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99564618" took 79 milliseconds May 29 09:58:45 volumio-alpha volumio[1392]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 09:58:45 volumio-alpha volumio[1392]: verbose: ControllerMpd::sendMpdCommand play May 29 09:58:45 volumio-alpha volumio[1392]: info: sendMpdCommand play took 1 milliseconds May 29 09:58:48 volumio-alpha go-librespot[1649]: time="2025-05-29T09:58:48+01:00" level=trace msg="sent dealer ping" May 29 09:58:48 volumio-alpha go-librespot[1649]: time="2025-05-29T09:58:48+01:00" level=trace msg="received dealer pong" May 29 09:58:49 volumio-alpha go-librespot[1649]: time="2025-05-29T09:58:49+01:00" level=trace msg="received accesspoint ping" May 29 09:58:49 volumio-alpha go-librespot[1649]: time="2025-05-29T09:58:49+01:00" level=trace msg="received accesspoint pong ack" May 29 09:58:55 volumio-alpha volumio[1392]: Searching plugin music_service/webradio May 29 09:58:55 volumio-alpha volumio[1392]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 29 09:58:55 volumio-alpha volumio[1392]: Use XMLElement.setAttribute instead May 29 09:58:55 volumio-alpha volumio[1392]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 09:58:55 volumio-alpha volumio[1392]: TypeError: children[i].attr(...).value is not a function May 29 09:58:55 volumio-alpha volumio[1392]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 29 09:58:55 volumio-alpha volumio[1392]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 29 09:58:55 volumio-alpha volumio[1392]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 09:58:55 volumio-alpha sudo[29843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 09:57' May 29 09:58:55 volumio-alpha sudo[29843]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 28 19:10:18 UTC 2025" VOLUMIO_VERSION="4.003" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"