Dec 11 21:46:01 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 11 21:46:01 volumio-pi5 volumio[1399]: info: In handleBrowseUri, curUri=spotify:artist:42a96EK26nA8ZPMlA8KcZt Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:2V4WkvtORbhGqnyklzEiHo Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:2E8aZ7jX73QAGWa6QWSxmB Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:68oo23TVrYEjm32ke8OK1l Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:6tKlpDrzwBjzzriOSnDMpC Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:5OK42AcDM0VjgJbSZRcBxw Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:5CC9LRQRQVlMn2c8SzbkOj Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:0x0H7OQ1Iu7vvUTpDNHdVI Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:4fXsImnWgYT88PW2IVZd4W Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:1rKe5cmNnGmARcXpuyM6lj Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:3xTrJw0qe1EI05RGZraJiT Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:46:02 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:46:09 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:09+01:00" level=debug msg="fetched chunk 6/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:46:17 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:17+01:00" level=trace msg="received accesspoint ping" Dec 11 21:46:17 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:17+01:00" level=trace msg="received accesspoint pong ack" Dec 11 21:46:17 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:17+01:00" level=trace msg="sent dealer ping" Dec 11 21:46:17 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:17+01:00" level=trace msg="received dealer pong" Dec 11 21:46:21 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:21+01:00" level=debug msg="fetched chunk 7/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:46:24 volumio-pi5 volumio[1399]: Searching plugin music_service/spop Dec 11 21:46:24 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 11 21:46:25 volumio-pi5 volumio[1399]: info: All search sources collected, pushing search results Dec 11 21:46:26 volumio-pi5 volumio[1399]: Searching plugin music_service/spop Dec 11 21:46:26 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 11 21:46:27 volumio-pi5 volumio[1399]: info: All search sources collected, pushing search results Dec 11 21:46:31 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:31+01:00" level=debug msg="fetched chunk 8/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:46:34 volumio-pi5 volumio[1399]: Searching plugin music_service/spop Dec 11 21:46:34 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 11 21:46:34 volumio-pi5 volumio[1399]: info: All search sources collected, pushing search results Dec 11 21:46:41 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:41+01:00" level=debug msg="fetched chunk 9/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:46:47 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:47+01:00" level=trace msg="sent dealer ping" Dec 11 21:46:47 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:47+01:00" level=trace msg="received dealer pong" Dec 11 21:46:53 volumio-pi5 volumio[1399]: Searching plugin music_service/spop Dec 11 21:46:53 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 11 21:46:53 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:46:53+01:00" level=debug msg="fetched chunk 10/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:46:54 volumio-pi5 volumio[1399]: info: All search sources collected, pushing search results Dec 11 21:46:56 volumio-pi5 volumio[1399]: Searching plugin music_service/spop Dec 11 21:46:56 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 11 21:46:56 volumio-pi5 volumio[1399]: Searching plugin music_service/spop Dec 11 21:46:56 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 11 21:46:56 volumio-pi5 volumio[1399]: info: All search sources collected, pushing search results Dec 11 21:46:57 volumio-pi5 volumio[1399]: info: All search sources collected, pushing search results Dec 11 21:47:05 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:05+01:00" level=debug msg="fetched chunk 11/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:47:15 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:15+01:00" level=debug msg="fetched chunk 12/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:47:17 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:17+01:00" level=trace msg="sent dealer ping" Dec 11 21:47:17 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:17+01:00" level=trace msg="received dealer pong" Dec 11 21:47:25 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:25+01:00" level=debug msg="fetched chunk 13/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:47:26 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 11 21:47:26 volumio-pi5 volumio[1399]: info: In handleBrowseUri, curUri=spotify:artist:3eCiuAk4AEplDESJXaXHMK Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:6m2pQYDZN6TgIbM0YAA9AF Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:0LxTAgYiwgtLtIwVl6qanT Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:2nZ7aMnHVxiM2eGAta89s9 Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:6lvjKTDxuZoFBMJbHbzkUK Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:1BtH6P4oQah3dUUSx0X3Fr Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:3D76bCLSmkmYERFPo7keHp Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:5N24YRJJIGwWrrZjboeZ12 Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:4YU9pBUTnN5BQUupmYyQW9 Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:3ZSNSvjscLJGqShV9wdX1I Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:4XiQUtLtylhidPgarUVmNk Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:47:27 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:47:35 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:35+01:00" level=debug msg="fetched chunk 14/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:47:42 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 11 21:47:42 volumio-pi5 volumio[1399]: info: In handleBrowseUri, curUri=spotify:artist:3z3IDoBLaQVmhHcCLGkGqe Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:3rHkqMuq5gNt5Zelt0tIYj Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:6IMdWYO0i52siAktV1DaeJ Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:5KyHmdK9Wmu8MSJw6cZHuq Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:2N3NHTUT1BGpkBBQL7iTb7 Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:5aRWrQMspic0MAaBh0rs5h Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:6rPTzmN7fhOWid8m40M3X1 Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:6zn2wLh1sj6Hyd5D7IIyoM Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:2hysEiQQLOd1vdyjdmQExK Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:7kGvJY0Cwe1eDC7xus6384 Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preloading song: spotify:track:0JeeK0x3WKByjrUJgvDklz Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:47:43 volumio-pi5 volumio[1399]: info: Preload queue cleared Dec 11 21:47:45 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:45+01:00" level=debug msg="fetched chunk 15/18, size: 524288" uri="spotify:track:0FTmksd2dxiE5e3rWyJXs6" Dec 11 21:47:47 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:47+01:00" level=trace msg="sent dealer ping" Dec 11 21:47:47 volumio-pi5 go-librespot[3124]: time="2025-12-11T21:47:47+01:00" level=trace msg="received dealer pong" Dec 11 21:47:51 volumio-pi5 volumio[1399]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 11 21:47:51 volumio-pi5 volumio[1399]: info: In handleBrowseUri, curUri=spotify:artist:1rf2KHg1LSDEcf19f3xXPM Dec 11 21:47:51 volumio-pi5 volumio[1399]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 21:47:51 volumio-pi5 volumio[1399]: TypeError: Cannot read properties of undefined (reading 'url') Dec 11 21:47:51 volumio-pi5 volumio[1399]: at /data/plugins/music_service/spop/index.js:2449:60 Dec 11 21:47:51 volumio-pi5 volumio[1399]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 11 21:47:51 volumio-pi5 volumio[1399]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 21:47:51 volumio-pi5 sudo[6017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 21:46' Dec 11 21:47:51 volumio-pi5 sudo[6017]: 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"