Dec 14 15:02:14 den go-librespot[20433]: time="2025-12-14T15:02:14-05:00" level=trace msg="sent dealer ping"
Dec 14 15:02:14 den go-librespot[20433]: time="2025-12-14T15:02:14-05:00" level=trace msg="received dealer pong"
Dec 14 15:02:44 den go-librespot[20433]: time="2025-12-14T15:02:44-05:00" level=trace msg="received accesspoint ping"
Dec 14 15:02:44 den go-librespot[20433]: time="2025-12-14T15:02:44-05:00" level=trace msg="received accesspoint pong ack"
Dec 14 15:02:44 den go-librespot[20433]: time="2025-12-14T15:02:44-05:00" level=trace msg="sent dealer ping"
Dec 14 15:02:44 den go-librespot[20433]: time="2025-12-14T15:02:44-05:00" level=trace msg="received dealer pong"
Dec 14 15:03:10 den volumio[1267]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.50 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Dec 14 15:03:10 den volumio[1267]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.50 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Dec 14 15:03:10 den volumio[1267]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.50 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::volumioGetState
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 14 15:03:11 den volumio[1267]: info: Received Get System Info
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 14 15:03:11 den volumio[1267]: info: Discovery: Getting this device information
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::volumioGetState
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::volumioGetState
Dec 14 15:03:11 den volumio[1267]: info: Listing playlists
Dec 14 15:03:11 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Dec 14 15:03:13 den volumio[1267]: info: CoreCommandRouter::volumioGetQueue
Dec 14 15:03:13 den volumio[1267]: info: CoreStateMachine::getQueue
Dec 14 15:03:13 den volumio[1267]: info: CorePlayQueue::getQueue
Dec 14 15:03:14 den go-librespot[20433]: time="2025-12-14T15:03:14-05:00" level=trace msg="sent dealer ping"
Dec 14 15:03:14 den go-librespot[20433]: time="2025-12-14T15:03:14-05:00" level=trace msg="received dealer pong"
Dec 14 15:03:15 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Dec 14 15:03:15 den volumio[1267]: info: In handleBrowseUri, curUri=spotify
Dec 14 15:03:15 den volumio[1267]: info: Preload queue cleared
Dec 14 15:03:15 den volumio[1267]: info: Preload queue cleared
Dec 14 15:03:15 den volumio[1267]: info: Preload queue cleared
Dec 14 15:03:15 den volumio[1267]: info: Preload queue cleared
Dec 14 15:03:17 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Dec 14 15:03:17 den volumio[1267]: info: In handleBrowseUri, curUri=spotify/myalbums
Dec 14 15:03:18 den volumio[1267]: info: Preload queue cleared
Dec 14 15:03:21 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Dec 14 15:03:21 den volumio[1267]: info: In handleBrowseUri, curUri=spotify/playlists
Dec 14 15:03:22 den volumio[1267]: info: Preload queue cleared
Dec 14 15:03:25 den volumio[1267]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Dec 14 15:03:25 den volumio[1267]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:0XqcxQdtaZUKZWPBbwBwxp
Dec 14 15:03:25 den volumio[1267]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 14 15:03:25 den volumio[1267]: TypeError: Cannot read properties of null (reading '0')
Dec 14 15:03:25 den volumio[1267]: at /data/plugins/music_service/spop/index.js:2507:56
Dec 14 15:03:25 den volumio[1267]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
Dec 14 15:03:25 den volumio[1267]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 14 15:03:26 den sudo[20736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 15:02'
Dec 14 15:03:26 den sudo[20736]: 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"