Dec 12 21:19:01 volumio volumio[8179]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Dec 12 21:19:01 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Dec 12 21:19:02 volumio sudo[11086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e 's/load-extension=.* /load-extension=\'\\/data\\/volumiokioskextensions\\/VirtualKeyboard\\/,\\/data\\/volumiokioskextensions\\/IframeKeyboardBridge\\/\' /' /opt/volumiokiosk.sh Dec 12 21:19:02 volumio sudo[11086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 21:19:02 volumio sudo[11086]: pam_unix(sudo:session): session closed for user root Dec 12 21:19:02 volumio sudo[11089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Dec 12 21:19:02 volumio sudo[11089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 21:19:02 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Dec 12 21:19:02 volumio startx[1744]: xinit: connection to X server lost Dec 12 21:19:02 volumio startx[1744]: Dec 12 21:19:03 volumio startx[1744]: waiting for X server to shut down Dec 12 21:19:03 volumio startx[1745]: (II) Server terminated successfully (0). Closing log file. Dec 12 21:19:04 volumio startx[1744]: . Dec 12 21:19:04 volumio startx[1744]: xinit: unexpected signal 15 Dec 12 21:19:04 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 12 21:19:04 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 12 21:19:04 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Dec 12 21:19:04 volumio systemd[1]: volumio-kiosk.service: Consumed 15min 1.703s CPU time. Dec 12 21:19:04 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 12 21:19:04 volumio sudo[11089]: pam_unix(sudo:session): session closed for user root Dec 12 21:19:04 volumio volumio[8179]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Dec 12 21:19:04 volumio volumio[8179]: info: touch_display: Volumio Kiosk restarted. Dec 12 21:19:04 volumio volumio[8179]: info: touch_display: X display number found: Dec 12 21:19:04 volumio startx[11132]: X.Org X Server 1.21.1.7 Dec 12 21:19:04 volumio startx[11132]: X Protocol Version 11, Revision 0 Dec 12 21:19:04 volumio startx[11132]: Current Operating System: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Dec 12 21:19:04 volumio startx[11132]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=6ed49b54-6938-466e-8ae8-dcb321b12e8d uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 12 21:19:04 volumio startx[11132]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Dec 12 21:19:04 volumio startx[11132]: Current version of pixman: 0.44.0 Dec 12 21:19:04 volumio startx[11132]: Before reporting problems, check http://wiki.x.org Dec 12 21:19:04 volumio startx[11132]: to make sure that you have the latest version. Dec 12 21:19:04 volumio startx[11132]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 12 21:19:04 volumio startx[11132]: (++) from command line, (!!) notice, (II) informational, Dec 12 21:19:04 volumio startx[11132]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 12 21:19:04 volumio startx[11132]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Dec 12 21:19:04 2025 Dec 12 21:19:04 volumio volumio[8179]: info: touch_display: X display number found: 0 Dec 12 21:19:04 volumio startx[11132]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 12 21:19:04 volumio startx[11132]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 12 21:19:04 volumio volumio[8179]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 12 21:19:04 volumio volumio[8179]: info: touch_display: X display number found: 0 Dec 12 21:19:05 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:05 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:05 volumio volumio[8179]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 12 21:19:12 volumio go-librespot[8484]: time="2025-12-12T21:19:12+01:00" level=trace msg="sent dealer ping" Dec 12 21:19:12 volumio go-librespot[8484]: time="2025-12-12T21:19:12+01:00" level=trace msg="received dealer pong" Dec 12 21:19:17 volumio volumio[8179]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 12 21:19:20 volumio volumio[8179]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:23 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::volumioGetQueue Dec 12 21:19:23 volumio volumio[8179]: info: CoreStateMachine::getQueue Dec 12 21:19:23 volumio volumio[8179]: info: CorePlayQueue::getQueue Dec 12 21:19:23 volumio volumio[8179]: info: Listing playlists Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 21:19:23 volumio volumio[8179]: info: Received Get System Info Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 21:19:23 volumio volumio[8179]: info: Discovery: Getting this device information Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:23 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:23 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 21:19:23 volumio volumio[8179]: info: Received Get System Info Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 21:19:23 volumio volumio[8179]: info: Discovery: Getting this device information Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:23 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:23 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 21:19:24 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 21:19:25 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 12 21:19:26 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 21:19:26 volumio volumio[8179]: info: Received Get System Info Dec 12 21:19:26 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 21:19:26 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 21:19:26 volumio volumio[8179]: info: Discovery: Getting this device information Dec 12 21:19:26 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:26 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:26 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 21:19:27 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 21:19:27 volumio volumio[8179]: info: Received Get System Info Dec 12 21:19:27 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 21:19:27 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 21:19:27 volumio volumio[8179]: info: Discovery: Getting this device information Dec 12 21:19:27 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:27 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:27 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 21:19:30 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 21:19:38 volumio volumio[8179]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 12 21:19:38 volumio volumio[8179]: info: Received Get System Version Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 21:19:38 volumio volumio[8179]: info: Received Get System Info Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 21:19:38 volumio volumio[8179]: info: Discovery: Getting this device information Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::volumioGetState Dec 12 21:19:38 volumio volumio[8179]: info: CorePlayQueue::getTrack 0 Dec 12 21:19:38 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 21:19:42 volumio go-librespot[8484]: time="2025-12-12T21:19:42+01:00" level=trace msg="received accesspoint ping" Dec 12 21:19:42 volumio go-librespot[8484]: time="2025-12-12T21:19:42+01:00" level=trace msg="received accesspoint pong ack" Dec 12 21:19:42 volumio go-librespot[8484]: time="2025-12-12T21:19:42+01:00" level=trace msg="sent dealer ping" Dec 12 21:19:42 volumio go-librespot[8484]: time="2025-12-12T21:19:42+01:00" level=trace msg="received dealer pong" Dec 12 21:19:53 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:19:53 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:19:54 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:19:54 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:19:54 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:19:55 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:12 volumio go-librespot[8484]: time="2025-12-12T21:20:12+01:00" level=trace msg="sent dealer ping" Dec 12 21:20:12 volumio go-librespot[8484]: time="2025-12-12T21:20:12+01:00" level=trace msg="received dealer pong" Dec 12 21:20:13 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:13 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:13 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:14 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:14 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:15 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:18 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:18 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:18 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:22 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:22 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:23 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:25 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:25 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:25 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:26 volumio nmbd[1178]: [2025/12/12 21:20:26.038466, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 12 21:20:26 volumio nmbd[1178]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.27 for name WORKGROUP<1d>. Dec 12 21:20:26 volumio nmbd[1178]: This response was from IP 192.168.178.19, reporting an IP address of 192.168.178.19. Dec 12 21:20:27 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:27 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:28 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:32 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:32 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:33 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:34 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:34 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:35 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:40 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:40 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:41 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:42 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:42 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:42 volumio go-librespot[8484]: time="2025-12-12T21:20:42+01:00" level=trace msg="sent dealer ping" Dec 12 21:20:42 volumio go-librespot[8484]: time="2025-12-12T21:20:42+01:00" level=trace msg="received dealer pong" Dec 12 21:20:43 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:45 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:45 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:45 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:48 volumio volumio[8179]: Searching plugin music_service/spop Dec 12 21:20:48 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 12 21:20:49 volumio volumio[8179]: info: All search sources collected, pushing search results Dec 12 21:20:52 volumio volumio[8179]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 12 21:20:52 volumio volumio[8179]: info: In handleBrowseUri, curUri=spotify:artist:5KZBXDeg5SR7aRAUcYK8Du Dec 12 21:20:53 volumio volumio[8179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 21:20:53 volumio volumio[8179]: TypeError: Cannot read properties of undefined (reading 'url') Dec 12 21:20:53 volumio volumio[8179]: at /data/plugins/music_service/spop/index.js:2449:60 Dec 12 21:20:53 volumio volumio[8179]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 12 21:20:53 volumio volumio[8179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 21:20:56 volumio sudo[11773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 21:19' Dec 12 21:20:56 volumio sudo[11773]: 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"