Jan 10 21:00:03 volumio-dom volumio[1398]: info: Jan 10 21:00:03 volumio-dom volumio[1398]: ---------------------------- MPD announces system playlist update Jan 10 21:00:03 volumio-dom volumio[1398]: info: Ignoring MPD Status Update Jan 10 21:00:03 volumio-dom volumio[1398]: info: Jan 10 21:00:03 volumio-dom volumio[1398]: ---------------------------- MPD announces state update: player Jan 10 21:00:03 volumio-dom volumio[1398]: info: ControllerMpd::getState Jan 10 21:00:03 volumio-dom volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:00:03 volumio-dom volumio[1398]: info: ------------------------------ 5ms Jan 10 21:00:03 volumio-dom volumio[1398]: info: sendMpdCommand status took 3 milliseconds Jan 10 21:00:03 volumio-dom volumio[1398]: verbose: ControllerMpd::parseState Jan 10 21:00:03 volumio-dom volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:00:03 volumio-dom volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 21:00:03 volumio-dom volumio[1398]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:00:03 volumio-dom volumio[1398]: info: ControllerMpd::pushState Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreCommandRouter::servicePushState Jan 10 21:00:03 volumio-dom volumio[1398]: info: CorePlayQueue::getTrack 0 Jan 10 21:00:03 volumio-dom volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47602,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FB_TOTH - ","artist":"Radio Caroline Flashback","album":null,"uri":"http://sc2.radiocaroline.net:10558/","trackType":"net:10558/"} Jan 10 21:00:03 volumio-dom volumio[1398]: verbose: CURRENT POSITION 0 Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreStateMachine::syncState stateService play Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:00:03 volumio-dom volumio[1398]: info: Received an update from plugin. extracting info from payload Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:00:03 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:00:03 volumio-dom volumio[1398]: info: ------------------------------ 13ms Jan 10 21:00:03 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 21:00:03 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 21:00:15 volumio-dom volumio[1398]: info: Jan 10 21:00:15 volumio-dom volumio[1398]: ---------------------------- MPD announces system playlist update Jan 10 21:00:15 volumio-dom volumio[1398]: info: Ignoring MPD Status Update Jan 10 21:00:15 volumio-dom volumio[1398]: info: Jan 10 21:00:15 volumio-dom volumio[1398]: ---------------------------- MPD announces state update: player Jan 10 21:00:15 volumio-dom volumio[1398]: info: ControllerMpd::getState Jan 10 21:00:15 volumio-dom volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Jan 10 21:00:15 volumio-dom volumio[1398]: info: ------------------------------ 3ms Jan 10 21:00:15 volumio-dom volumio[1398]: info: sendMpdCommand status took 3 milliseconds Jan 10 21:00:15 volumio-dom volumio[1398]: verbose: ControllerMpd::parseState Jan 10 21:00:15 volumio-dom volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 21:00:15 volumio-dom volumio[1398]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 21:00:15 volumio-dom volumio[1398]: verbose: ControllerMpd::parseTrackInfo Jan 10 21:00:15 volumio-dom volumio[1398]: info: ControllerMpd::pushState Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreCommandRouter::servicePushState Jan 10 21:00:15 volumio-dom volumio[1398]: info: CorePlayQueue::getTrack 0 Jan 10 21:00:15 volumio-dom volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":60226,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AH-1 - ","artist":"Radio Caroline Flashback","album":null,"uri":"http://sc2.radiocaroline.net:10558/","trackType":"net:10558/"} Jan 10 21:00:15 volumio-dom volumio[1398]: verbose: CURRENT POSITION 0 Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreStateMachine::syncState stateService play Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreStateMachine::syncState currentStatus play Jan 10 21:00:15 volumio-dom volumio[1398]: info: Received an update from plugin. extracting info from payload Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:00:15 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:00:15 volumio-dom volumio[1398]: info: ------------------------------ 16ms Jan 10 21:00:15 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 21:00:15 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Jan 10 21:00:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:00:18+01:00" level=trace msg="sent dealer ping" Jan 10 21:00:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:00:18+01:00" level=trace msg="received dealer pong" Jan 10 21:00:23 volumio-dom volumio[1398]: info: [1768075223285] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Jan 10 21:00:23 volumio-dom volumio[1398]: info: [1768075223864] [RadioParadise] received new metadata: {"time":282,"artist":"Susheela Raman","title":"Orama","album":"Salt Rain","year":"2001","cover":"https://img.radioparadise.com/covers/l/9513.jpg","cover_med":"https://img.radioparadise.com/covers/m/9513.jpg","cover_small":"https://img.radioparadise.com/covers/s/9513.jpg"} Jan 10 21:00:23 volumio-dom volumio[1398]: info: CoreCommandRouter::servicePushState Jan 10 21:00:23 volumio-dom volumio[1398]: info: CorePlayQueue::getTrack 0 Jan 10 21:00:23 volumio-dom volumio[1398]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received radio_paradise Jan 10 21:00:23 volumio-dom volumio[1398]: info: [1768075223865] [RadioParadise] setting new timer with duration of 287000 seconds. Jan 10 21:00:23 volumio-dom volumio[1398]: info: CorePlayQueue::getTrack 0 Jan 10 21:00:23 volumio-dom volumio[1398]: info: CorePlayQueue::getTrack 0 Jan 10 21:00:23 volumio-dom volumio[1398]: info: Prefetching next song Jan 10 21:00:29 volumio-dom volumio[1398]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 21:00:29 volumio-dom volumio[1398]: info: CoreCommandRouter::Close All Modals sent Jan 10 21:00:48 volumio-dom go-librespot[1783]: time="2026-01-10T21:00:48+01:00" level=trace msg="sent dealer ping" Jan 10 21:00:48 volumio-dom go-librespot[1783]: time="2026-01-10T21:00:48+01:00" level=trace msg="received dealer pong" Jan 10 21:01:14 volumio-dom volumio[1398]: info: VolumeController::SetAlsaVolume79 Jan 10 21:01:14 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:01:14 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:01:14 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:01:14 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jan 10 21:01:14 volumio-dom volumio[1398]: SPOTIFY: SPOTIFY VOLUME 45 Jan 10 21:01:14 volumio-dom volumio[1398]: SPOTIFY: VOLUMIO VOLUME 79 Jan 10 21:01:14 volumio-dom volumio[1398]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 21:01:14 volumio-dom volumio[1398]: info: Setting Spotify Volume from Volumio: 79 Jan 10 21:01:15 volumio-dom volumio[1398]: info: VolumeController::SetAlsaVolume62 Jan 10 21:01:15 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:01:15 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:01:15 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: SPOTIFY VOLUME 79 Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: VOLUMIO VOLUME 62 Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 21:01:15 volumio-dom volumio[1398]: info: Setting Spotify Volume from Volumio: 62 Jan 10 21:01:15 volumio-dom volumio[1398]: info: VolumeController::SetAlsaVolume57 Jan 10 21:01:15 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:01:15 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:01:15 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: SPOTIFY VOLUME 62 Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: VOLUMIO VOLUME 57 Jan 10 21:01:15 volumio-dom volumio[1398]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 21:01:15 volumio-dom volumio[1398]: info: Setting Spotify Volume from Volumio: 57 Jan 10 21:01:16 volumio-dom volumio[1398]: info: VolumeController::SetAlsaVolume47 Jan 10 21:01:16 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:01:16 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:01:16 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: SPOTIFY VOLUME 57 Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: VOLUMIO VOLUME 47 Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 21:01:16 volumio-dom volumio[1398]: info: Setting Spotify Volume from Volumio: 47 Jan 10 21:01:16 volumio-dom volumio[1398]: info: VolumeController::SetAlsaVolume44 Jan 10 21:01:16 volumio-dom volumio[1398]: info: CoreStateMachine::pushState Jan 10 21:01:16 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:01:16 volumio-dom volumio[1398]: info: CoreCommandRouter::volumioPushState Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: SPOTIFY VOLUME 47 Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: VOLUMIO VOLUME 44 Jan 10 21:01:16 volumio-dom volumio[1398]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 21:01:16 volumio-dom volumio[1398]: info: Setting Spotify Volume from Volumio: 44 Jan 10 21:01:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:18+01:00" level=trace msg="sent dealer ping" Jan 10 21:01:18 volumio-dom volumio[1398]: SPOTIFY: SETTING SPOTIFY VOLUME 44 Jan 10 21:01:18 volumio-dom volumio[1398]: info: Sending Spotify command with payload to local API: /player/volume Jan 10 21:01:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:18+01:00" level=trace msg="received dealer pong" Jan 10 21:01:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:18+01:00" level=debug msg="update volume requested to 28835/65535" Jan 10 21:01:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:18+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 10 21:01:18 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:18+01:00" level=trace msg="emitting websocket event: volume" Jan 10 21:01:18 volumio-dom volumio[1398]: SPOTIFY: received: {"type":"volume","data":{"value":44,"max":100}} Jan 10 21:01:18 volumio-dom volumio[1398]: SPOTIFY: RECEIVED SPOTIFY VOLUME 44 Jan 10 21:01:45 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 10 21:01:45 volumio-dom volumio[1398]: info: CURURI: genres:// Jan 10 21:01:45 volumio-dom volumio[1398]: info: Preload queue cleared Jan 10 21:01:48 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:48+01:00" level=trace msg="sent dealer ping" Jan 10 21:01:48 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:48+01:00" level=trace msg="received dealer pong" Jan 10 21:01:49 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:49+01:00" level=trace msg="received accesspoint ping" Jan 10 21:01:49 volumio-dom go-librespot[1783]: time="2026-01-10T21:01:49+01:00" level=trace msg="received accesspoint pong ack" Jan 10 21:01:54 volumio-dom volumio[1398]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preload queue cleared Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: http://stream.radioparadise.com/global-flacm Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz.mp4 Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:4vLYewWIvqHfKtJDk8c8tq Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:3KNYBgLWLY3kmDwp0IzdVd Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:12FfwiXnxEP49jSi3Cojhn Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:5dLwdEJAFzjQbeRrDL5uzo Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:6rqn19AqjcM5wgI1hCYciJ Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:3it2BvJfyhPEWF3kD2TZJ1 Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:5NB3bYzlLT2Q1bS8DKR7Z1 Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:3uKV8rCdY6PACPIPNJSgNw Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:0Ol63DFImXunGh2veZg2Ib Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:63dUleGMmDHfR3nQIQ79Wf Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:6p5HahKfrxjxkGz5jRVbkB Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:2368r2D53RhpfIlLBHAXLl Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:19fbN84F5YHDyv8dyTbCO8 Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:2rMK6uQxoSGYWOXtzPUY4Z Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:1FZP4hpT4iuLaOb5HpriLq Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:47CctmFYrX4htszDZCVaLM Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:0zLmRVBlZJ3IJTkChSXkBp Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:27fIrMVmHLxP2Hot6qVxsy Jan 10 21:01:54 volumio-dom volumio[1398]: info: Preloading song: spotify:track:36vFi2qWu4QlSi0xFEvSJ1 Jan 10 21:01:54 volumio-dom volumio[1398]: info: Exploding uri https://stream.radioparadise.com/rock-flacm in service radio_paradise Jan 10 21:01:54 volumio-dom volumio[1398]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/rock-flacm" Jan 10 21:01:54 volumio-dom volumio[1398]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 21:01:54 volumio-dom volumio[1398]: TypeError: Cannot read properties of undefined (reading 'then') Jan 10 21:01:54 volumio-dom volumio[1398]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Jan 10 21:01:54 volumio-dom volumio[1398]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jan 10 21:01:54 volumio-dom volumio[1398]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jan 10 21:01:54 volumio-dom volumio[1398]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jan 10 21:01:54 volumio-dom volumio[1398]: at listOnTimeout (node:internal/timers:573:17) Jan 10 21:01:54 volumio-dom volumio[1398]: at process.processTimers (node:internal/timers:514:7) Jan 10 21:01:54 volumio-dom volumio[1398]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 21:01:55 volumio-dom sudo[23577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 21:00' Jan 10 21:01:55 volumio-dom sudo[23577]: 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"