Jan 12 08:34:01 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:01 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:01 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:01 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:01 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:03 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:03 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:03 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:03 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:03 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:05 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:05 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:05 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:05 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:05 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:07 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:07 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:07 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:07 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:07 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:09 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:09 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:09 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:09 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:09 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:10 volumio-test go-librespot[5102]: time="2026-01-12T08:34:10-07:00" level=trace msg="sent dealer ping" Jan 12 08:34:10 volumio-test go-librespot[5102]: time="2026-01-12T08:34:10-07:00" level=trace msg="received dealer pong" Jan 12 08:34:10 volumio-test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11065. Jan 12 08:34:10 volumio-test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:10 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:10 volumio-test upmpdcli[1109]: Could not open config: /tmp/upmpdcli.conf Jan 12 08:34:10 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 12 08:34:10 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 12 08:34:11 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:11 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:11 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:11 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:11 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::ClearQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::stop Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:12 volumio-test volumio[1678]: info: CorePlayQueue::clearPlayQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CorePlayQueue::saveQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreCommandRouter::volumioPushQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::addQueueItems Jan 12 08:34:12 volumio-test volumio[1678]: info: CorePlayQueue::addQueueItems Jan 12 08:34:12 volumio-test volumio[1678]: info: Preload queue cleared Jan 12 08:34:12 volumio-test volumio[1678]: info: Adding Item to queue: radio/selection Jan 12 08:34:12 volumio-test volumio[1678]: info: Using cached record of: radio/selection Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreCommandRouter::volumioPushQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CorePlayQueue::saveQueue Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::updateTrackBlock Jan 12 08:34:12 volumio-test volumio[1678]: info: CorePlayQueue::getTrackBlock Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreCommandRouter::volumioPlay Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::play index 0 Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::stop Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::play index undefined Jan 12 08:34:12 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:12 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::ClearQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::stop Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::clearPlayQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::saveQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioPushQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::addQueueItems Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::addQueueItems Jan 12 08:34:13 volumio-test volumio[1678]: info: Preload queue cleared Jan 12 08:34:13 volumio-test volumio[1678]: info: Adding Item to queue: radio/selection Jan 12 08:34:13 volumio-test volumio[1678]: info: Using cached record of: radio/selection Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioPushQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::saveQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::updateTrackBlock Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::getTrackBlock Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioPlay Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::play index 0 Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::stop Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::play index undefined Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:13 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:14 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 08:34:14 volumio-test volumio[1678]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 08:34:15 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetState Jan 12 08:34:15 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:34:15 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:15 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:15 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:15 volumio-test volumio[1678]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 12 08:34:17 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:17 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:17 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:19 volumio-test volumio[1678]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 12 08:34:19 volumio-test volumio[1678]: info: Getting BBC Radios Jan 12 08:34:19 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:19 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:19 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:21 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:21 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:21 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:23 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:23 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:23 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:25 volumio-test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11066. Jan 12 08:34:25 volumio-test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:25 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:25 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:25 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:25 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:25 volumio-test upmpdcli[1141]: Could not open config: /tmp/upmpdcli.conf Jan 12 08:34:25 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 12 08:34:25 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 12 08:34:27 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:27 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:27 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:29 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:29 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:29 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:31 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:31 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:31 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:33 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:33 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:33 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:35 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:35 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:35 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:37 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:37 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:37 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:39 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 08:34:39 volumio-test volumio[1678]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 08:34:39 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:39 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:39 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:40 volumio-test go-librespot[5102]: time="2026-01-12T08:34:40-07:00" level=error msg="did not receive last pong ack from accesspoint, 135s passed" Jan 12 08:34:40 volumio-test go-librespot[5102]: time="2026-01-12T08:34:40-07:00" level=trace msg="sent dealer ping" Jan 12 08:34:40 volumio-test go-librespot[5102]: time="2026-01-12T08:34:40-07:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.1.139:36548->104.154.127.247:80: use of closed network connection" Jan 12 08:34:40 volumio-test go-librespot[5102]: time="2026-01-12T08:34:40-07:00" level=trace msg="received dealer pong" Jan 12 08:34:40 volumio-test go-librespot[5102]: time="2026-01-12T08:34:40-07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Jan 12 08:34:40 volumio-test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11067. Jan 12 08:34:40 volumio-test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:40 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:40 volumio-test upmpdcli[1160]: Could not open config: /tmp/upmpdcli.conf Jan 12 08:34:40 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 12 08:34:40 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 12 08:34:41 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:41 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:41 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:43 volumio-test go-librespot[5102]: time="2026-01-12T08:34:43-07:00" level=debug msg="connected to ap-gue1.spotify.com:443" Jan 12 08:34:43 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:43 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:43 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:45 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:45 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:45 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:45 volumio-test volumio[1678]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 12 08:34:47 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:47 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:47 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:49 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:49 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:49 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:50 volumio-test volumio[1678]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 12 08:34:50 volumio-test volumio[1678]: info: Getting webradio selection Jan 12 08:34:51 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:51 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:51 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="completed keyexchange" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="completed challenge" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=info msg="authenticated AP" username="cr**********us" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="re-established accesspoint connection" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=trace msg="received accesspoint ping" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 12 08:34:52 volumio-test go-librespot[5102]: time="2026-01-12T08:34:52-07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 12 08:34:53 volumio-test go-librespot[5102]: time="2026-01-12T08:34:53-07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 12 08:34:53 volumio-test go-librespot[5102]: time="2026-01-12T08:34:53-07:00" level=trace msg="received accesspoint pong ack" Jan 12 08:34:53 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:53 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:53 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:55 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:55 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:55 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:55 volumio-test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11068. Jan 12 08:34:55 volumio-test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:55 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:34:56 volumio-test upmpdcli[1190]: Could not open config: /tmp/upmpdcli.conf Jan 12 08:34:56 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 12 08:34:56 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 12 08:34:57 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:57 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:57 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:34:59 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:34:59 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:34:59 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:01 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:01 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:01 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:03 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:03 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:03 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:04 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:04 volumio-test volumio[1678]: info: CorePlayQueue::getTrack 0 Jan 12 08:35:04 volumio-test volumio[1678]: info: [1768232104454] ControllerPandora::ExpireOldTracks::reaper Jan 12 08:35:04 volumio-test volumio[1678]: info: [1768232104454] [Pandora] ExpireOldTracks::reaper: No victims found: Expiring zero tracks. Don't worry -- Jason will return. Jan 12 08:35:05 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:05 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:05 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:07 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:07 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:07 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:09 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:09 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:09 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:10 volumio-test go-librespot[5102]: time="2026-01-12T08:35:10-07:00" level=trace msg="sent dealer ping" Jan 12 08:35:10 volumio-test go-librespot[5102]: time="2026-01-12T08:35:10-07:00" level=trace msg="received dealer pong" Jan 12 08:35:11 volumio-test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11069. Jan 12 08:35:11 volumio-test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:35:11 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:35:11 volumio-test upmpdcli[1209]: Could not open config: /tmp/upmpdcli.conf Jan 12 08:35:11 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 12 08:35:11 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 12 08:35:11 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:11 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:11 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:13 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:13 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:13 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:15 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:15 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:15 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:17 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:17 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:17 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:19 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:19 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:19 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:21 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:21 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:21 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:23 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:23 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:23 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:25 volumio-test volumio[1678]: info: CoreCommandRouter::volumioGetQueue Jan 12 08:35:25 volumio-test volumio[1678]: info: CoreStateMachine::getQueue Jan 12 08:35:25 volumio-test volumio[1678]: info: CorePlayQueue::getQueue Jan 12 08:35:26 volumio-test volumio[1678]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 08:35:26 volumio-test volumio[1678]: Error Jan 12 08:35:26 volumio-test volumio[1678]: at /volumio/app/plugins/music_service/webradio/index.js:427:31 Jan 12 08:35:26 volumio-test volumio[1678]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:444:15) Jan 12 08:35:26 volumio-test volumio[1678]: at self.callback (/volumio/node_modules/request/request.js:185:22) Jan 12 08:35:26 volumio-test volumio[1678]: at Request.emit (node:events:514:28) Jan 12 08:35:26 volumio-test volumio[1678]: at Request.onRequestError (/volumio/node_modules/request/request.js:877:8) Jan 12 08:35:26 volumio-test volumio[1678]: at ClientRequest.emit (node:events:514:28) Jan 12 08:35:26 volumio-test volumio[1678]: at Socket.socketErrorListener (node:_http_client:495:9) Jan 12 08:35:26 volumio-test volumio[1678]: at Socket.emit (node:events:514:28) Jan 12 08:35:26 volumio-test volumio[1678]: at emitErrorNT (node:internal/streams/destroy:151:8) Jan 12 08:35:26 volumio-test volumio[1678]: at emitErrorCloseNT (node:internal/streams/destroy:116:3) Jan 12 08:35:26 volumio-test volumio[1678]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 08:35:26 volumio-test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11070. Jan 12 08:35:26 volumio-test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:35:26 volumio-test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 12 08:35:26 volumio-test upmpdcli[1253]: Could not open config: /tmp/upmpdcli.conf Jan 12 08:35:26 volumio-test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 12 08:35:26 volumio-test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 12 08:35:27 volumio-test sudo[1257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 08:34' Jan 12 08:35:27 volumio-test sudo[1257]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"