Mar 28 13:00:01 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:01Z" level=trace msg="sent dealer ping" Mar 28 13:00:01 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:01Z" level=trace msg="received dealer pong" Mar 28 13:00:28 volumio-screen volumio[1273]: info: VolumeController::SetAlsaVolume99 Mar 28 13:00:28 volumio-screen volumio[1273]: info: CoreStateMachine::pushState Mar 28 13:00:28 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:00:28 volumio-screen volumio[1273]: info: CoreCommandRouter::volumioPushState Mar 28 13:00:28 volumio-screen volumio[1273]: info: MRS: Pushing multiroomSync output update for this device Mar 28 13:00:28 volumio-screen volumio[1273]: info: MRS: Pushing multiroomSync output Mar 28 13:00:28 volumio-screen volumio[1273]: info: CoreCommandRouter::volumioGetState Mar 28 13:00:28 volumio-screen volumio[1273]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Mar 28 13:00:28 volumio-screen volumio[1273]: SPOTIFY: SPOTIFY VOLUME 94 Mar 28 13:00:28 volumio-screen volumio[1273]: SPOTIFY: VOLUMIO VOLUME 99 Mar 28 13:00:28 volumio-screen volumio[1273]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 28 13:00:28 volumio-screen volumio[1273]: info: Setting Spotify Volume from Volumio: 99 Mar 28 13:00:29 volumio-screen volumio[1273]: SPOTIFY: SETTING SPOTIFY VOLUME 99 Mar 28 13:00:29 volumio-screen volumio[1273]: info: Sending Spotify command with payload to local API: /player/volume Mar 28 13:00:29 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:29Z" level=debug msg="update volume requested to 64879/65535" Mar 28 13:00:30 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:30Z" level=debug msg="put connect state because VOLUME_CHANGED" Mar 28 13:00:30 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:30Z" level=trace msg="emitting websocket event: volume" Mar 28 13:00:30 volumio-screen volumio[1273]: SPOTIFY: received: {"type":"volume","data":{"value":99,"max":100}} Mar 28 13:00:30 volumio-screen volumio[1273]: SPOTIFY: RECEIVED SPOTIFY VOLUME 99 Mar 28 13:00:31 volumio-screen volumio[1273]: info: Executing endpoint metavolumio Mar 28 13:00:31 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 13:00:31 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:31Z" level=trace msg="received accesspoint ping" Mar 28 13:00:31 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:31Z" level=trace msg="received accesspoint pong ack" Mar 28 13:00:31 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:31Z" level=trace msg="sent dealer ping" Mar 28 13:00:31 volumio-screen go-librespot[1861]: time="2026-03-28T13:00:31Z" level=trace msg="received dealer pong" Mar 28 13:00:57 volumio-screen volumio[1273]: info: CoreCommandRouter::volumioGetState Mar 28 13:00:59 volumio-screen volumio[1273]: info: Executing endpoint metavolumio Mar 28 13:00:59 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 13:01:01 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 28 13:01:01 volumio-screen volumio[1273]: info: Preload queue cleared Mar 28 13:01:01 volumio-screen go-librespot[1861]: time="2026-03-28T13:01:01Z" level=trace msg="sent dealer ping" Mar 28 13:01:01 volumio-screen go-librespot[1861]: time="2026-03-28T13:01:01Z" level=trace msg="received dealer pong" Mar 28 13:01:02 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 28 13:01:02 volumio-screen volumio[1273]: info: Preload queue cleared Mar 28 13:01:03 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 28 13:01:04 volumio-screen volumio[1273]: info: Preload queue cleared Mar 28 13:01:07 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 28 13:01:08 volumio-screen volumio[1273]: info: Preload queue cleared Mar 28 13:01:27 volumio-screen kernel: edt_ft5x06 10-0038: Unable to fetch data, error: -121 Mar 28 13:01:31 volumio-screen go-librespot[1861]: time="2026-03-28T13:01:31Z" level=trace msg="sent dealer ping" Mar 28 13:01:31 volumio-screen go-librespot[1861]: time="2026-03-28T13:01:31Z" level=trace msg="received dealer pong" Mar 28 13:01:41 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 28 13:01:41 volumio-screen volumio[1273]: info: Preload queue cleared Mar 28 13:01:43 volumio-screen volumio[1273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 28 13:01:43 volumio-screen volumio[1273]: info: Preload queue cleared Mar 28 13:01:48 volumio-screen volumio[1273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:01:48 volumio-screen volumio[1273]: TypeError: Cannot read properties of null (reading 'slice') Mar 28 13:01:48 volumio-screen volumio[1273]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 28 13:01:48 volumio-screen volumio[1273]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 28 13:01:48 volumio-screen volumio[1273]: at Object.onceWrapper (node:events:629:26) Mar 28 13:01:48 volumio-screen volumio[1273]: at ClientRequest.emit (node:events:514:28) Mar 28 13:01:48 volumio-screen volumio[1273]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 28 13:01:48 volumio-screen volumio[1273]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 28 13:01:48 volumio-screen volumio[1273]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 28 13:01:48 volumio-screen volumio[1273]: at TLSSocket.emit (node:events:514:28) Mar 28 13:01:48 volumio-screen volumio[1273]: at addChunk (node:internal/streams/readable:343:12) Mar 28 13:01:48 volumio-screen volumio[1273]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 28 13:01:48 volumio-screen volumio[1273]: at Readable.push (node:internal/streams/readable:253:10) Mar 28 13:01:48 volumio-screen volumio[1273]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 28 13:01:48 volumio-screen volumio[1273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:01:49 volumio-screen sudo[5148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 13:00' Mar 28 13:01:49 volumio-screen sudo[5148]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"