Feb 12 15:45:03 volumio-salon go-librespot[27096]: time="2026-02-12T15:45:03+01:00" level=trace msg="received accesspoint ping" Feb 12 15:45:03 volumio-salon go-librespot[27096]: time="2026-02-12T15:45:03+01:00" level=trace msg="received accesspoint pong ack" Feb 12 15:45:03 volumio-salon go-librespot[27096]: time="2026-02-12T15:45:03+01:00" level=trace msg="sent dealer ping" Feb 12 15:45:03 volumio-salon go-librespot[27096]: time="2026-02-12T15:45:03+01:00" level=trace msg="received dealer pong" Feb 12 15:45:14 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1007. Feb 12 15:45:14 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:45:14 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:45:14 volumio-salon upmpdcli[27437]: Could not open config: /tmp/upmpdcli.conf Feb 12 15:45:14 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 15:45:14 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 15:45:19 volumio-salon volumio[26727]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.5.11 UA: Mozilla/5.0 (Linux; Android 16; A059 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 15:45:19 volumio-salon volumio[26727]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 12 15:45:19 volumio-salon volumio[26727]: info: Received Get System Info Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:45:19 volumio-salon volumio[26727]: info: Discovery: Getting this device information Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:45:19 volumio-salon volumio[26727]: info: Listing playlists Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetQueue Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreStateMachine::getQueue Feb 12 15:45:19 volumio-salon volumio[26727]: info: CorePlayQueue::getQueue Feb 12 15:45:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 12 15:45:22 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 12 15:45:22 volumio-salon volumio[26727]: info: [jellyfin-browse] browseUri: jellyfin Feb 12 15:45:22 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:45:23 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 12 15:45:23 volumio-salon volumio[26727]: info: [jellyfin-browse] browseUri: jellyfin/Thibs@3ff2963d7e2841ab9f719ab079158688 Feb 12 15:45:23 volumio-salon volumio[26727]: info: [jellyfin-conn] Using previously assigned device Id for Thibs@jellyfin-lxc: bcd75a3c-9dde-4b62-9c97-25d783c03d0b Feb 12 15:45:24 volumio-salon volumio[26727]: info: [jellyfin-conn] Login successful: Thibs@jellyfin-lxc Feb 12 15:45:24 volumio-salon volumio[26727]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f1dc8e9509454b83b89eae3e3bc7976c","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Feb 12 15:45:25 volumio-salon volumio[26727]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.0.19:8096/UserViews?userId=f1dc8e9509454b83b89eae3e3bc7976c Feb 12 15:45:25 volumio-salon volumio[26727]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f1dc8e9509454b83b89eae3e3bc7976c","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"5e1aff96135d5e13105f021e6bea2793","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Feb 12 15:45:25 volumio-salon volumio[26727]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.0.19:8096/Items?userId=f1dc8e9509454b83b89eae3e3bc7976c&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=5e1aff96135d5e13105f021e6bea2793&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Feb 12 15:45:25 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:45:25 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:45:26 volumio-salon wpa_supplicant[1341]: RRM: Ignoring radio measurement request: Not RRM network Feb 12 15:45:28 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 12 15:45:28 volumio-salon volumio[26727]: info: [jellyfin-browse] browseUri: jellyfin/Thibs@3ff2963d7e2841ab9f719ab079158688/songs@albumId=9c384e1379be870e40f8ce728a51c96e Feb 12 15:45:28 volumio-salon volumio[26727]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f1dc8e9509454b83b89eae3e3bc7976c","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"9c384e1379be870e40f8ce728a51c96e","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Feb 12 15:45:29 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1008. Feb 12 15:45:29 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:45:29 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:45:29 volumio-salon upmpdcli[27467]: Could not open config: /tmp/upmpdcli.conf Feb 12 15:45:29 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 15:45:29 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 15:45:33 volumio-salon go-librespot[27096]: time="2026-02-12T15:45:33+01:00" level=trace msg="sent dealer ping" Feb 12 15:45:33 volumio-salon go-librespot[27096]: time="2026-02-12T15:45:33+01:00" level=trace msg="received dealer pong" Feb 12 15:45:44 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1009. Feb 12 15:45:44 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:45:44 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:45:44 volumio-salon upmpdcli[27483]: Could not open config: /tmp/upmpdcli.conf Feb 12 15:45:44 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 15:45:44 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 15:45:52 volumio-salon wpa_supplicant[1341]: RRM: Ignoring radio measurement request: Not RRM network Feb 12 15:46:00 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1010. Feb 12 15:46:00 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:46:00 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:46:00 volumio-salon upmpdcli[27513]: Could not open config: /tmp/upmpdcli.conf Feb 12 15:46:00 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 15:46:00 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 15:46:03 volumio-salon go-librespot[27096]: time="2026-02-12T15:46:03+01:00" level=trace msg="sent dealer ping" Feb 12 15:46:03 volumio-salon go-librespot[27096]: time="2026-02-12T15:46:03+01:00" level=trace msg="received dealer pong" Feb 12 15:46:08 volumio-salon volumio5-onboarding[2232]: time=2026-02-12T15:46:08.127+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.5.11:48404 @ 0x311c450" latency=-196.584122ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 12 15:46:08 volumio-salon sudo[27530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 15:46:08 volumio-salon sudo[27530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:46:08 volumio-salon sudo[27530]: pam_unix(sudo:session): session closed for user root Feb 12 15:46:08 volumio-salon sudo[27532]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 15:46:08 volumio-salon sudo[27532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:46:08 volumio-salon sudo[27532]: pam_unix(sudo:session): session closed for user root Feb 12 15:46:08 volumio-salon volumio[26727]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.5.11 UA: Mozilla/5.0 (Linux; Android 16; A059 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 12 15:46:08 volumio-salon sudo[27536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 15:46:08 volumio-salon sudo[27536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:46:08 volumio-salon sudo[27536]: pam_unix(sudo:session): session closed for user root Feb 12 15:46:08 volumio-salon sudo[27538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 15:46:08 volumio-salon sudo[27538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 15:46:08 volumio-salon sudo[27538]: pam_unix(sudo:session): session closed for user root Feb 12 15:46:09 volumio-salon volumio[26727]: verbose: New Socket.io Connection to 192.168.0.91 from 192.168.5.11 UA: Mozilla/5.0 (Linux; Android 16; A059 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetQueue Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreStateMachine::getQueue Feb 12 15:46:09 volumio-salon volumio[26727]: info: CorePlayQueue::getQueue Feb 12 15:46:09 volumio-salon volumio[26727]: info: Listing playlists Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 15:46:09 volumio-salon volumio[26727]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 12 15:46:09 volumio-salon volumio[26727]: info: Received Get System Info Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:46:09 volumio-salon volumio[26727]: info: Discovery: Getting this device information Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 15:46:09 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 12 15:46:10 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 12 15:46:10 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:46:10 volumio-salon volumio[26727]: info: Received Get System Info Feb 12 15:46:10 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:46:10 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:46:10 volumio-salon volumio[26727]: info: Discovery: Getting this device information Feb 12 15:46:10 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:46:10 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:46:11 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:46:11 volumio-salon volumio[26727]: info: Received Get System Info Feb 12 15:46:11 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:46:11 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:46:11 volumio-salon volumio[26727]: info: Discovery: Getting this device information Feb 12 15:46:11 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:46:11 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:46:15 volumio-salon systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1011. Feb 12 15:46:15 volumio-salon systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:46:15 volumio-salon systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 15:46:15 volumio-salon upmpdcli[27541]: Could not open config: /tmp/upmpdcli.conf Feb 12 15:46:15 volumio-salon systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 15:46:15 volumio-salon systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 15:46:17 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 12 15:46:17 volumio-salon volumio[26727]: info: In handleBrowseUri, curUri=spotify Feb 12 15:46:17 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:46:17 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:46:17 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:46:17 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:46:17 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 15:46:17 volumio-salon volumio[26727]: info: Received Get System Info Feb 12 15:46:17 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 15:46:17 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 15:46:17 volumio-salon volumio[26727]: info: Discovery: Getting this device information Feb 12 15:46:17 volumio-salon volumio[26727]: info: CoreCommandRouter::volumioGetState Feb 12 15:46:17 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 15:46:18 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 12 15:46:18 volumio-salon volumio[26727]: info: In handleBrowseUri, curUri=spotify/playlists Feb 12 15:46:18 volumio-salon volumio[26727]: info: Preload queue cleared Feb 12 15:46:19 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 12 15:46:19 volumio-salon wpa_supplicant[1341]: RRM: Ignoring radio measurement request: Not RRM network Feb 12 15:46:25 volumio-salon volumio[26727]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 12 15:46:25 volumio-salon volumio[26727]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:2YT6IQr77Zg3vz11FT8nLM Feb 12 15:46:26 volumio-salon volumio[26727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 15:46:26 volumio-salon volumio[26727]: TypeError: Cannot read properties of null (reading '0') Feb 12 15:46:26 volumio-salon volumio[26727]: at /data/plugins/music_service/spop/index.js:2507:56 Feb 12 15:46:26 volumio-salon volumio[26727]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 12 15:46:26 volumio-salon volumio[26727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 15:46:26 volumio-salon sudo[27585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 15:45' Feb 12 15:46:26 volumio-salon sudo[27585]: 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"