Jan 09 20:59:09 volumio volumio[1172]: info: Jan 09 20:59:09 volumio volumio[1172]: ---------------------------- MPD announces system playlist update Jan 09 20:59:09 volumio volumio[1172]: info: Ignoring MPD Status Update Jan 09 20:59:09 volumio volumio[1172]: info: Jan 09 20:59:09 volumio volumio[1172]: ---------------------------- MPD announces state update: player Jan 09 20:59:09 volumio volumio[1172]: info: ControllerMpd::getState Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:59:09 volumio volumio[1172]: info: Jan 09 20:59:09 volumio volumio[1172]: ---------------------------- MPD announces system playlist update Jan 09 20:59:09 volumio volumio[1172]: info: Ignoring MPD Status Update Jan 09 20:59:09 volumio volumio[1172]: info: Jan 09 20:59:09 volumio volumio[1172]: ---------------------------- MPD announces state update: player Jan 09 20:59:09 volumio volumio[1172]: info: ControllerMpd::getState Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jan 09 20:59:09 volumio volumio[1172]: info: ------------------------------ 5ms Jan 09 20:59:09 volumio volumio[1172]: info: sendMpdCommand status took 4 milliseconds Jan 09 20:59:09 volumio volumio[1172]: info: ------------------------------ 2ms Jan 09 20:59:09 volumio volumio[1172]: info: sendMpdCommand status took 1 milliseconds Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::parseState Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::parseState Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 20:59:09 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 09 20:59:09 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:59:09 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jan 09 20:59:09 volumio volumio[1172]: info: ControllerMpd::pushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Jan 09 20:59:09 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Jan 09 20:59:09 volumio volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":184089,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Kaśka Sochacka - Szum","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 09 20:59:09 volumio volumio[1172]: verbose: CURRENT POSITION 0 Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::syncState stateService play Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus play Jan 09 20:59:09 volumio volumio[1172]: info: Received an update from plugin. extracting info from payload Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::pushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::pushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Jan 09 20:59:09 volumio volumio[1172]: info: ControllerMpd::pushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Jan 09 20:59:09 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Jan 09 20:59:09 volumio volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":184089,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Kaśka Sochacka - Szum","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 09 20:59:09 volumio volumio[1172]: verbose: CURRENT POSITION 0 Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::syncState stateService play Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus play Jan 09 20:59:09 volumio volumio[1172]: info: Received an update from plugin. extracting info from payload Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::pushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreStateMachine::pushState Jan 09 20:59:09 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Jan 09 20:59:09 volumio volumio[1172]: info: ------------------------------ 29ms Jan 09 20:59:09 volumio volumio[1172]: info: ------------------------------ 32ms Jan 09 20:59:09 volumio volumio[1172]: info: FusionDsp - Volumio is playing Jan 09 20:59:09 volumio volumio[1172]: info: FusionDsp - Volumio is playing Jan 09 20:59:09 volumio volumio[1172]: info: FusionDsp - Volumio is playing Jan 09 20:59:09 volumio volumio[1172]: info: FusionDsp - Volumio is playing Jan 09 20:59:09 volumio volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jan 09 20:59:09 volumio volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jan 09 20:59:09 volumio volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jan 09 20:59:09 volumio volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Jan 09 20:59:13 volumio go-librespot[133089]: time="2026-01-09T20:59:13+01:00" level=trace msg="sent dealer ping" Jan 09 20:59:13 volumio go-librespot[133089]: time="2026-01-09T20:59:13+01:00" level=trace msg="received dealer pong" Jan 09 20:59:43 volumio go-librespot[133089]: time="2026-01-09T20:59:43+01:00" level=trace msg="sent dealer ping" Jan 09 20:59:43 volumio go-librespot[133089]: time="2026-01-09T20:59:43+01:00" level=trace msg="received dealer pong" Jan 09 20:59:54 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 09 20:59:54 volumio volumio[1172]: info: In handleBrowseUri, curUri=spotify/playlists Jan 09 20:59:54 volumio volumio[1172]: info: Preload queue cleared Jan 09 21:00:08 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 09 21:00:08 volumio volumio[1172]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:6mdYLBs7PzVlPlvDHaMUfo Jan 09 21:00:09 volumio volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 21:00:09 volumio volumio[1172]: TypeError: Cannot read properties of null (reading '0') Jan 09 21:00:09 volumio volumio[1172]: at /data/plugins/music_service/spop/index.js:2504:56 Jan 09 21:00:09 volumio volumio[1172]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 09 21:00:09 volumio volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 21:00:09 volumio sudo[133435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-09 20:59' Jan 09 21:00:09 volumio sudo[133435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"