Jan 25 10:51:02 volumio volumio[1177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1MTCiRbvPK93YY6LNIeEbc","service":"spop","name":"It's Not Right But It's Okay","artist":"Felix Jaehn","album":"It's Not Right But It's Okay","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273d3a18564a591c9a592a2380f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Jan 25 10:51:03 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:51:03 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:51:03 volumio volumio[1177]: info: Listing playlists
Jan 25 10:51:03 volumio volumio[1177]: info: Listing playlists
Jan 25 10:51:03 volumio volumio[1177]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5wtSei6pJpDEB1iKqPHhfU","service":"spop","name":"Houdini","artist":"Dua Lipa","album":"Houdini","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273b931be9989996605dbafe4e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}]
Jan 25 10:51:07 volumio ntpd[1000]: PROTO: 83.215.130.11 unlink local addr 192.168.8.11 ->
Jan 25 10:51:13 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:51:13 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:51:15 volumio ntpd[1000]: PROTO: 152.53.51.182 unlink local addr 192.168.8.11 ->
Jan 25 10:51:16 volumio ntpd[1000]: PROTO: 78.41.116.149 unlink local addr 192.168.8.11 ->
Jan 25 10:51:17 volumio go-librespot[2817]: time="2026-01-25T10:51:17+01:00" level=trace msg="sent dealer ping"
Jan 25 10:51:17 volumio go-librespot[2817]: time="2026-01-25T10:51:17+01:00" level=trace msg="received dealer pong"
Jan 25 10:51:23 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:51:23 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:51:23 volumio volumio[1177]: info: Listing playlists
Jan 25 10:51:23 volumio volumio[1177]: info: Listing playlists
Jan 25 10:51:29 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 25 10:51:29 volumio volumio[1177]: info: In handleBrowseUri, curUri=spotify
Jan 25 10:51:30 volumio volumio[1177]: info: Preload queue cleared
Jan 25 10:51:30 volumio volumio[1177]: info: Preload queue cleared
Jan 25 10:51:30 volumio volumio[1177]: info: Preload queue cleared
Jan 25 10:51:30 volumio volumio[1177]: info: Preload queue cleared
Jan 25 10:51:33 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:51:33 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:51:41 volumio ntpd[1000]: PROTO: 152.53.100.131 unlink local addr 192.168.8.11 ->
Jan 25 10:51:43 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:51:43 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:51:43 volumio volumio[1177]: info: Listing playlists
Jan 25 10:51:43 volumio volumio[1177]: info: Listing playlists
Jan 25 10:51:46 volumio go-librespot[2817]: time="2026-01-25T10:51:46+01:00" level=trace msg="received accesspoint ping"
Jan 25 10:51:46 volumio go-librespot[2817]: time="2026-01-25T10:51:46+01:00" level=trace msg="received accesspoint pong ack"
Jan 25 10:51:47 volumio go-librespot[2817]: time="2026-01-25T10:51:47+01:00" level=trace msg="sent dealer ping"
Jan 25 10:51:47 volumio go-librespot[2817]: time="2026-01-25T10:51:47+01:00" level=trace msg="received dealer pong"
Jan 25 10:51:53 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:51:53 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:52:03 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:52:03 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:52:03 volumio volumio[1177]: info: Listing playlists
Jan 25 10:52:03 volumio volumio[1177]: info: Listing playlists
Jan 25 10:52:13 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:52:13 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:52:17 volumio go-librespot[2817]: time="2026-01-25T10:52:17+01:00" level=trace msg="sent dealer ping"
Jan 25 10:52:17 volumio go-librespot[2817]: time="2026-01-25T10:52:17+01:00" level=trace msg="received dealer pong"
Jan 25 10:52:19 volumio ntpd[1000]: PROTO: 152.53.119.76 unlink local addr 192.168.8.11 ->
Jan 25 10:52:22 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 25 10:52:22 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 25 10:52:23 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:52:23 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:52:23 volumio volumio[1177]: info: Listing playlists
Jan 25 10:52:23 volumio volumio[1177]: info: Listing playlists
Jan 25 10:52:23 volumio ntpd[1000]: PROTO: 86.59.113.124 unlink local addr 192.168.8.11 ->
Jan 25 10:52:24 volumio volumio[1177]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 25 10:52:24 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 25 10:52:27 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 25 10:52:27 volumio volumio[1177]: info: In handleBrowseUri, curUri=spotify
Jan 25 10:52:30 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 25 10:52:30 volumio volumio[1177]: info: In handleBrowseUri, curUri=spotify/playlists
Jan 25 10:52:33 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jan 25 10:52:33 volumio volumio[1177]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:3paghdLyPJKIAbAGo6Ke4e
Jan 25 10:52:33 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState
Jan 25 10:52:33 volumio volumio[1177]: info: CorePlayQueue::getTrack 0
Jan 25 10:52:33 volumio volumio[1177]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 25 10:52:33 volumio volumio[1177]: TypeError: Cannot read properties of null (reading '0')
Jan 25 10:52:33 volumio volumio[1177]: at /data/plugins/music_service/spop/index.js:2504:56
Jan 25 10:52:33 volumio volumio[1177]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
Jan 25 10:52:33 volumio volumio[1177]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 25 10:52:33 volumio sudo[3308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 10:51'
Jan 25 10:52:33 volumio sudo[3308]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"