Dec 18 21:12:12 volumio go-librespot[11804]: time="2025-12-18T21:12:12+01:00" level=trace msg="received accesspoint ping" Dec 18 21:12:12 volumio go-librespot[11804]: time="2025-12-18T21:12:12+01:00" level=trace msg="received accesspoint pong ack" Dec 18 21:12:29 volumio go-librespot[11804]: time="2025-12-18T21:12:29+01:00" level=trace msg="sent dealer ping" Dec 18 21:12:29 volumio go-librespot[11804]: time="2025-12-18T21:12:29+01:00" level=trace msg="received dealer pong" Dec 18 21:12:32 volumio volumio[11612]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; XQ-CC54 Build/65.2.A.2.270; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 21:12:32 volumio volumio[11612]: info: Received Get System Info Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 21:12:32 volumio volumio[11612]: info: Discovery: Getting this device information Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::volumioGetState Dec 18 21:12:32 volumio volumio[11612]: info: Listing playlists Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::volumioGetQueue Dec 18 21:12:32 volumio volumio[11612]: info: CoreStateMachine::getQueue Dec 18 21:12:32 volumio volumio[11612]: info: CorePlayQueue::getQueue Dec 18 21:12:32 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 18 21:12:43 volumio go-librespot[11804]: time="2025-12-18T21:12:43+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4JAvHpjipBk" Dec 18 21:12:43 volumio go-librespot[11804]: time="2025-12-18T21:12:43+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 18 21:12:46 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 18 21:12:46 volumio volumio[11612]: info: In handleBrowseUri, curUri=spotify Dec 18 21:12:47 volumio volumio[11612]: info: New access token = BQB-DbKq_wB4Ru2ux7QTPV5N5B55EuyScUZcp6PZ2s1vy3YPs8iWz90t8ZPZT9c65N0T20uNd6myRlynhlUiVAqkcJhD8Sw8Ux7WkKZA4xS92IzivFUj3JpGurrXgoct8CurDcWCVsSXSeiy58P7-vwH9LlccAzAVxmsJKnCArMNoc4-AERhcJqwHj19--4BcI-lAVJGS-5mHYCQ5r3Pmr5DZv3cSEAC8HN0UrTx5-V9RbZ-Vfk3GCC3QNg0x3R4nmc-w0M8s4yoa372eM1WzwhIGzgFE_cLxpo Dec 18 21:12:47 volumio volumio[11612]: info: New access token = BQA5pz4ayymH3UwP598f088BWGbLmVCQC7MfqdUnAdTf9dFabsB8Z9lMx0DxgH9ZY5TFirSxHJb-N9lFO2_AJvSEzaoTfe7XoVWTae7mtff__LRU4k3QSTjsWAJKRS4fFRNPUXF-wOpFnNVd3k1PKIafJbHiS-ZjkXRWAMb-A9AygZbeyIimoSZDMNtNqWqwTZAvlU7nqO4FCvqZse64ZMb_vwaCncYvQqPRRdqBPmxRBZ5x2cQizv0peN1l6h0xrNj1mBxS8PTk4hW_Wi_DtAkxI6cu50g7xkM Dec 18 21:12:47 volumio volumio[11612]: info: New access token = BQCb_pgBqCwAWpxIcXShPYxV5jEk3QvAWnl21PBfF4UDTuHqkTTCt4uRN3rMR755LPHq24rVaRPayak2jrASBusm8Y2k6KwWDG11QW30hhz-FijehgPVcGi5ZoIqQ1lKRNMfMppwmtBTAtXkQ9OWpcxkS7Mq5AnIKfsAQTcQbCZlTjwpYDt-Jd5TdeJi2j3GqBW3-f0sDrxjba7QTdvqMPm-Wmz6GcbIeNNaWL5qWfPPXXHwvQmEFhdENg4aN5RNFZJE9hiQKn25pZ9kD2-lDierbYmdaG6rq64 Dec 18 21:12:48 volumio volumio[11612]: info: Preload queue cleared Dec 18 21:12:48 volumio volumio[11612]: info: Preload queue cleared Dec 18 21:12:48 volumio volumio[11612]: info: Preload queue cleared Dec 18 21:12:48 volumio volumio[11612]: info: Preload queue cleared Dec 18 21:12:54 volumio go-librespot[11804]: time="2025-12-18T21:12:54+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 18 21:12:54 volumio go-librespot[11804]: time="2025-12-18T21:12:54+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4wjfFxajQ4Is" Dec 18 21:12:54 volumio go-librespot[11804]: time="2025-12-18T21:12:54+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIXKz6Ujos91g" Dec 18 21:12:54 volumio go-librespot[11804]: time="2025-12-18T21:12:54+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 18 21:12:59 volumio go-librespot[11804]: time="2025-12-18T21:12:59+01:00" level=trace msg="sent dealer ping" Dec 18 21:12:59 volumio go-librespot[11804]: time="2025-12-18T21:12:59+01:00" level=trace msg="received dealer pong" Dec 18 21:13:01 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 18 21:13:01 volumio volumio[11612]: info: In handleBrowseUri, curUri=spotify/playlists Dec 18 21:13:02 volumio volumio[11612]: info: Preload queue cleared Dec 18 21:13:12 volumio volumio[11612]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 18 21:13:12 volumio volumio[11612]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:5NmwUlW3rqFXOL0MeePBui Dec 18 21:13:12 volumio volumio[11612]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 21:13:12 volumio volumio[11612]: TypeError: Cannot read properties of null (reading '0') Dec 18 21:13:12 volumio volumio[11612]: at /data/plugins/music_service/spop/index.js:2507:56 Dec 18 21:13:12 volumio volumio[11612]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 18 21:13:12 volumio volumio[11612]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 21:13:13 volumio sudo[32040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 21:12' Dec 18 21:13:13 volumio sudo[32040]: 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"