Jan 09 22:21:08 volumio go-librespot[1412]: time="2026-01-09T22:21:08+01:00" level=debug msg="fetched chunk 13/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:21:15 volumio go-librespot[1412]: time="2026-01-09T22:21:15+01:00" level=trace msg="sent dealer ping" Jan 09 22:21:16 volumio go-librespot[1412]: time="2026-01-09T22:21:16+01:00" level=trace msg="received dealer pong" Jan 09 22:21:19 volumio go-librespot[1412]: time="2026-01-09T22:21:19+01:00" level=debug msg="fetched chunk 14/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:21:27 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jan 09 22:21:29 volumio go-librespot[1412]: time="2026-01-09T22:21:29+01:00" level=debug msg="fetched chunk 15/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:21:41 volumio go-librespot[1412]: time="2026-01-09T22:21:41+01:00" level=debug msg="fetched chunk 16/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:21:45 volumio go-librespot[1412]: time="2026-01-09T22:21:45+01:00" level=trace msg="sent dealer ping" Jan 09 22:21:46 volumio go-librespot[1412]: time="2026-01-09T22:21:46+01:00" level=trace msg="received dealer pong" Jan 09 22:21:52 volumio go-librespot[1412]: time="2026-01-09T22:21:52+01:00" level=debug msg="fetched chunk 17/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:22:04 volumio go-librespot[1412]: time="2026-01-09T22:22:04+01:00" level=debug msg="fetched chunk 18/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:22:15 volumio go-librespot[1412]: time="2026-01-09T22:22:15+01:00" level=debug msg="fetched chunk 19/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:22:15 volumio go-librespot[1412]: time="2026-01-09T22:22:15+01:00" level=trace msg="sent dealer ping" Jan 09 22:22:15 volumio go-librespot[1412]: time="2026-01-09T22:22:15+01:00" level=trace msg="received dealer pong" Jan 09 22:22:16 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 09 22:22:16 volumio volumio[1090]: info: In handleBrowseUri, curUri=spotify:artist:1pggi3bfbAPkwrylE63MEO Jan 09 22:22:17 volumio volumio[1090]: info: Preload queue cleared Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:1S7VWDpdcFFzNVg6ZQNpRg Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:4ViqRfZoOGFryXi8BDh6Gj Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:7G0jq4sJ8oFfpfRzjFqeND Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:1eDW38uiHvC5b5G0yCvLIt Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:1rXa0ktCVPEmEHXYjcT7yf Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:1popQOfGkea2VJ1VQLDL2X Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:6kmt2IIVsMcYPO0FrAR6US Jan 09 22:22:17 volumio volumio[1090]: info: Preloading song: spotify:track:7u1FUecu9iOM7AkEUHz1ce Jan 09 22:22:17 volumio volumio[1090]: info: Preload queue cleared Jan 09 22:22:17 volumio volumio[1090]: info: Preload queue cleared Jan 09 22:22:17 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:17 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:17 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:17 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 09 22:22:21 volumio volumio[1090]: info: Received Get System Info Jan 09 22:22:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 09 22:22:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 09 22:22:21 volumio volumio[1090]: info: Discovery: Getting this device information Jan 09 22:22:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Jan 09 22:22:21 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Jan 09 22:22:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 09 22:22:21 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jan 09 22:22:21 volumio dbus-daemon[572]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.251' (uid=0 pid=18283 comm="timedatectl show --property=NTPSynchronized --valu") Jan 09 22:22:21 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jan 09 22:22:21 volumio dbus-daemon[572]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 09 22:22:21 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jan 09 22:22:21 volumio setdatetime-helper.sh[18282]: Time is already synchronized. Jan 09 22:22:21 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jan 09 22:22:21 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jan 09 22:22:26 volumio go-librespot[1412]: time="2026-01-09T22:22:26+01:00" level=debug msg="fetched chunk 20/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:22:35 volumio volumio[1090]: Searching plugin music_service/spop Jan 09 22:22:35 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 09 22:22:35 volumio volumio[1090]: info: All search sources collected, pushing search results Jan 09 22:22:36 volumio volumio[1090]: Searching plugin music_service/spop Jan 09 22:22:36 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 09 22:22:36 volumio volumio[1090]: info: All search sources collected, pushing search results Jan 09 22:22:37 volumio go-librespot[1412]: time="2026-01-09T22:22:37+01:00" level=debug msg="fetched chunk 21/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:22:38 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 09 22:22:38 volumio volumio[1090]: info: In handleBrowseUri, curUri=spotify:artist:2WBJQGf1bT1kxuoqziH5g4 Jan 09 22:22:39 volumio volumio[1090]: info: Preload queue cleared Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:1zsFr9rPcsMwRtqjDJJpnC Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:6BjG4NirMgJfC6QAvbfBNG Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:75UUG6x5zQe0gCVOiQpa6X Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:1xiUU6OH3ytnZCBfaQQXe0 Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:3CTgRPxyTh2w7wBjVFH88e Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:5JBtWpLoGO62gb5vax505b Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:5Iv4HuT9pKPi5WuE2ii3vs Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:6NOlVkuAFtqTiBInHuqlrs Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:2YSObdVwb6sWJh0cFMYKqF Jan 09 22:22:39 volumio volumio[1090]: info: Preloading song: spotify:track:3HiwSiBqwXHy20VUVv44We Jan 09 22:22:39 volumio volumio[1090]: info: Preload queue cleared Jan 09 22:22:39 volumio volumio[1090]: info: Preload queue cleared Jan 09 22:22:39 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:39 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:39 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:39 volumio volumio[1090]: info: No valid Plugin REST Endpoint Jan 09 22:22:45 volumio go-librespot[1412]: time="2026-01-09T22:22:45+01:00" level=trace msg="sent dealer ping" Jan 09 22:22:45 volumio go-librespot[1412]: time="2026-01-09T22:22:45+01:00" level=trace msg="received dealer pong" Jan 09 22:22:47 volumio go-librespot[1412]: time="2026-01-09T22:22:47+01:00" level=trace msg="received accesspoint ping" Jan 09 22:22:47 volumio go-librespot[1412]: time="2026-01-09T22:22:47+01:00" level=trace msg="received accesspoint pong ack" Jan 09 22:22:48 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 09 22:22:48 volumio volumio[1090]: info: In handleBrowseUri, curUri=spotify:artist:71rMOOTcG9xSOzJt6X5Cqb Jan 09 22:22:49 volumio go-librespot[1412]: time="2026-01-09T22:22:49+01:00" level=debug msg="fetched chunk 22/37, size: 524288" uri="spotify:track:708DnJC8l1b4Bf4xg0GiBv" Jan 09 22:22:49 volumio volumio[1090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 22:22:49 volumio volumio[1090]: TypeError: Cannot read properties of undefined (reading 'url') Jan 09 22:22:49 volumio volumio[1090]: at /data/plugins/music_service/spop/index.js:2449:60 Jan 09 22:22:49 volumio volumio[1090]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 09 22:22:49 volumio volumio[1090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 22:22:50 volumio sudo[18344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-09 22:21' Jan 09 22:22:50 volumio sudo[18344]: 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="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"