Feb 25 17:25:15 volumio go-librespot[1682]: time="2026-02-25T17:25:15+01:00" level=trace msg="sent dealer ping" Feb 25 17:25:15 volumio go-librespot[1682]: time="2026-02-25T17:25:15+01:00" level=trace msg="received dealer pong" Feb 25 17:25:44 volumio volumio[1136]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.57 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 25 17:25:44 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:25:44 volumio volumio[1136]: info: CoreStateMachine::getQueue Feb 25 17:25:44 volumio volumio[1136]: info: CorePlayQueue::getQueue Feb 25 17:25:44 volumio volumio[1136]: info: Listing playlists Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 17:25:44 volumio volumio[1136]: info: Received Get System Info Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:25:44 volumio volumio[1136]: info: Discovery: Getting this device information Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 25 17:25:44 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 25 17:25:44 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 17:25:44 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 17:25:45 volumio go-librespot[1682]: time="2026-02-25T17:25:45+01:00" level=trace msg="sent dealer ping" Feb 25 17:25:45 volumio go-librespot[1682]: time="2026-02-25T17:25:45+01:00" level=trace msg="received dealer pong" Feb 25 17:25:46 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 17:25:46 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 17:25:46 volumio volumio[1136]: info: Received Get System Info Feb 25 17:25:46 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:25:46 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:25:46 volumio volumio[1136]: info: Discovery: Getting this device information Feb 25 17:25:46 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 25 17:25:46 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 25 17:25:46 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:25:47 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 17:25:47 volumio volumio[1136]: info: Received Get System Info Feb 25 17:25:47 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:25:47 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:25:47 volumio volumio[1136]: info: Discovery: Getting this device information Feb 25 17:25:47 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 25 17:25:47 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 25 17:25:47 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:25:54 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 17:26:02 volumio volumio[1136]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 25 17:26:02 volumio volumio[1136]: info: Received Get System Version Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 17:26:02 volumio volumio[1136]: info: Received Get System Info Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 17:26:02 volumio volumio[1136]: info: Discovery: Getting this device information Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::volumioGetState Feb 25 17:26:02 volumio volumio[1136]: info: CorePlayQueue::getTrack 0 Feb 25 17:26:02 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 17:26:07 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 17:26:07 volumio volumio[1136]: info: In handleBrowseUri, curUri=spotify Feb 25 17:26:08 volumio volumio[1136]: info: New access token = BQCI3HsPeKYzlMJMmZBOraR1CyVG83jRNyOYNy2xU9dq99xtah29j9T9-S4MB7EW6QrMiqTV-nGqcEq3tsSOWSTrUuxMTBJgU40R8-ESiWUIftcmXgCbcmKGnzOeR2VEMGI-5kQnfwGCNREhpuuF23HXq_LR8VAUaYwo_F2_up6yEvrnFScY9eMMJSM9YwPizrYpyG-2jDE0JQgeVBwiw2DfAGAgCRZn0SFntEkat0Za7GDdMscN8tkTzq6yBeUtJDY-HM_rCZi3zd__-AdhPS3Y_o_iJUOG Feb 25 17:26:08 volumio volumio[1136]: info: New access token = BQA8UFBCExL0QWtGhV9D71YUL7ogLgpJGELcubyIX1YO5m7YcvnfgELnOI7vYWYIa5uXGuF_iM5pil_vKXilLuCqWGLHdydn_Y6iELx4IRs3ZhBNboUVk2xrezqbqMvlv1c5ywkv1ZbmjCWHKDni0kn2BJgVY31V6_O7EKhjxL1OJgR5w6xlsslHu8cYxueY2-BOf4iY8UAD1IHbvxMQ3MS6iz2Hder4hD7qBtKeStnJHfrx5N8EaQ7I_7LywFRJAKnzq5SjIext3ag60ofWuFtU2kRNaQL4 Feb 25 17:26:08 volumio volumio[1136]: info: New access token = BQD4VxpzBlQMRPTEkksmyuZbckPpWLhd2cE622TFoC0NdeRn4vURIlVP5w2GEOTr6Br5F5P967AVVUFgoq9jHLDe2u67yLqREcG4fv1HM9Cs_v4NuGSNzwtdtid5pN4o26H0TLhN5Jc8Q8r6ZjfmJIUYmfPg1tg4bwe3HMEk6CVHClua-hDCxK28T5v80iEMKBoRCXg5ihHmXYcqdvCzCDDWNCpYyeJ43CquNB9N_Jfds4vfF3w-bBI62Fuu9SOVgHlS0XEHCWUjZ8I55oXaEa3M7i2fBs7n Feb 25 17:26:08 volumio volumio[1136]: info: Preload queue cleared Feb 25 17:26:08 volumio volumio[1136]: info: Preload queue cleared Feb 25 17:26:08 volumio volumio[1136]: info: Preload queue cleared Feb 25 17:26:08 volumio volumio[1136]: info: Preload queue cleared Feb 25 17:26:11 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 17:26:12 volumio volumio[1136]: info: In handleBrowseUri, curUri=spotify/playlists Feb 25 17:26:12 volumio volumio[1136]: info: Preload queue cleared Feb 25 17:26:15 volumio go-librespot[1682]: time="2026-02-25T17:26:15+01:00" level=trace msg="sent dealer ping" Feb 25 17:26:15 volumio go-librespot[1682]: time="2026-02-25T17:26:15+01:00" level=trace msg="received dealer pong" Feb 25 17:26:44 volumio go-librespot[1682]: time="2026-02-25T17:26:44+01:00" level=trace msg="received accesspoint ping" Feb 25 17:26:44 volumio go-librespot[1682]: time="2026-02-25T17:26:44+01:00" level=trace msg="received accesspoint pong ack" Feb 25 17:26:45 volumio go-librespot[1682]: time="2026-02-25T17:26:45+01:00" level=trace msg="sent dealer ping" Feb 25 17:26:45 volumio go-librespot[1682]: time="2026-02-25T17:26:45+01:00" level=trace msg="received dealer pong" Feb 25 17:26:45 volumio volumio[1136]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 25 17:26:45 volumio volumio[1136]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:3kxBcfQPzMhaWbYjMiyN6h Feb 25 17:26:46 volumio volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 17:26:46 volumio volumio[1136]: TypeError: Cannot read properties of null (reading '0') Feb 25 17:26:46 volumio volumio[1136]: at /data/plugins/music_service/spop/index.js:2504:56 Feb 25 17:26:46 volumio volumio[1136]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 17:26:46 volumio volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 17:26:48 volumio sudo[29560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 17:25' Feb 25 17:26:48 volumio sudo[29560]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"