Dec 23 14:59:05 volumio go-librespot[1471]: time="2025-12-23T14:59:05+01:00" level=trace msg="received accesspoint ping" Dec 23 14:59:05 volumio go-librespot[1471]: time="2025-12-23T14:59:05+01:00" level=trace msg="received accesspoint pong ack" Dec 23 14:59:06 volumio go-librespot[1471]: time="2025-12-23T14:59:06+01:00" level=trace msg="sent dealer ping" Dec 23 14:59:06 volumio go-librespot[1471]: time="2025-12-23T14:59:06+01:00" level=trace msg="received dealer pong" Dec 23 14:59:07 volumio go-librespot[1471]: time="2025-12-23T14:59:07+01:00" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:4PRO3r7lebb0az7T7TSDp1" Dec 23 14:59:11 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 14:59:11 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 14:59:12 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 14:59:12 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 14:59:12 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 14:59:13 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 14:59:18 volumio go-librespot[1471]: time="2025-12-23T14:59:18+01:00" level=debug msg="fetched chunk 12/16, size: 524288" uri="spotify:track:4PRO3r7lebb0az7T7TSDp1" Dec 23 14:59:18 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 23 14:59:18 volumio volumio[1131]: info: In handleBrowseUri, curUri=spotify:artist:0rVfCx1CrxThen0ILdn1h9 Dec 23 14:59:19 volumio volumio[1131]: info: Preload queue cleared Dec 23 14:59:19 volumio volumio[1131]: info: Preloading song: spotify:track:0sX0V2UWLlDG4mb8oqdpIx Dec 23 14:59:19 volumio volumio[1131]: info: Preloading song: spotify:track:5mM0wZxiXyZoA0KCNS86qa Dec 23 14:59:19 volumio volumio[1131]: info: Preloading song: spotify:track:2d0LcBlYaNeXlwvYYTv8j2 Dec 23 14:59:19 volumio volumio[1131]: info: Preloading song: spotify:track:6ymmdka6WWpbsKq6otpuyt Dec 23 14:59:19 volumio volumio[1131]: info: Preloading song: spotify:track:7Kfcf3SaZZKIbyt6BklD4p Dec 23 14:59:19 volumio volumio[1131]: info: Preload queue cleared Dec 23 14:59:19 volumio volumio[1131]: info: Preload queue cleared Dec 23 14:59:19 volumio volumio[1131]: info: No valid Plugin REST Endpoint Dec 23 14:59:29 volumio go-librespot[1471]: time="2025-12-23T14:59:29+01:00" level=debug msg="fetched chunk 13/16, size: 524288" uri="spotify:track:4PRO3r7lebb0az7T7TSDp1" Dec 23 14:59:30 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 14:59:30 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 14:59:30 volumio volumio[1131]: info: Retrieving Cloud Streaming UI Dec 23 14:59:30 volumio volumio[1131]: info: Getting Tidal Cloud Configuration Dec 23 14:59:30 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 14:59:30 volumio volumio[1131]: info: Getting Qobuz Cloud Configuration Dec 23 14:59:30 volumio volumio[1131]: info: Asking plugin for UI Config Dec 23 14:59:30 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 14:59:30 volumio volumio[1131]: info: Getting Spotify Cloud Configuration Dec 23 14:59:30 volumio volumio[1131]: info: Asking plugin for UI Config Dec 23 14:59:30 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 14:59:30 volumio volumio[1131]: info: Saving Spotify Acccount Dec 23 14:59:30 volumio volumio[1131]: info: Got Tidal Cloud Configuration Dec 23 14:59:30 volumio volumio[1131]: info: Got it Dec 23 14:59:30 volumio volumio[1131]: info: Got it Dec 23 14:59:30 volumio volumio[1131]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 23 14:59:30 volumio volumio[1131]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 14:59:30 volumio volumio[1131]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::volumioGetBrowseSources Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::volumioGetBrowseSources Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::volumioGetBrowseSources Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 23 14:59:31 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 23 14:59:36 volumio go-librespot[1471]: time="2025-12-23T14:59:36+01:00" level=trace msg="sent dealer ping" Dec 23 14:59:36 volumio go-librespot[1471]: time="2025-12-23T14:59:36+01:00" level=trace msg="received dealer pong" Dec 23 14:59:40 volumio go-librespot[1471]: time="2025-12-23T14:59:40+01:00" level=debug msg="fetched chunk 14/16, size: 524288" uri="spotify:track:4PRO3r7lebb0az7T7TSDp1" Dec 23 14:59:51 volumio go-librespot[1471]: time="2025-12-23T14:59:51+01:00" level=debug msg="fetched chunk 15/16, size: 524288" uri="spotify:track:4PRO3r7lebb0az7T7TSDp1" Dec 23 14:59:52 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 14:59:52 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 14:59:53 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 14:59:55 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 14:59:55 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 14:59:55 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 14:59:57 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 14:59:57 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 14:59:58 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 14:59:59 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 14:59:59 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 14:59:59 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 15:00:04 volumio go-librespot[1471]: time="2025-12-23T15:00:04+01:00" level=debug msg="fetched chunk 16/16, size: 320958" uri="spotify:track:4PRO3r7lebb0az7T7TSDp1" Dec 23 15:00:06 volumio go-librespot[1471]: time="2025-12-23T15:00:06+01:00" level=trace msg="sent dealer ping" Dec 23 15:00:06 volumio go-librespot[1471]: time="2025-12-23T15:00:06+01:00" level=trace msg="received dealer pong" Dec 23 15:00:06 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 15:00:06 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 15:00:07 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 15:00:08 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 15:00:08 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 15:00:09 volumio volumio[1131]: Searching plugin music_service/spop Dec 23 15:00:09 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 23 15:00:09 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 15:00:09 volumio volumio[1131]: info: All search sources collected, pushing search results Dec 23 15:00:18 volumio volumio[1131]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 23 15:00:18 volumio volumio[1131]: info: In handleBrowseUri, curUri=spotify:artist:5yCOPIBtOPKf8HykjO0KMO Dec 23 15:00:19 volumio volumio[1131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 15:00:19 volumio volumio[1131]: TypeError: Cannot read properties of undefined (reading 'url') Dec 23 15:00:19 volumio volumio[1131]: at /data/plugins/music_service/spop/index.js:2449:60 Dec 23 15:00:19 volumio volumio[1131]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 23 15:00:19 volumio volumio[1131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 15:00:20 volumio sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 14:59' Dec 23 15:00:20 volumio sudo[2248]: 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"