May 22 10:11:00 volumio volumio[1264]: info: CALLMETHOD: system_controller system saveHDMISettings [object Object] May 22 10:11:00 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , saveHDMISettings May 22 10:11:00 volumio sudo[3472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service May 22 10:11:00 volumio sudo[3472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 10:11:00 volumio startx[1366]: xinit: connection to X server lost May 22 10:11:00 volumio systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... May 22 10:11:00 volumio startx[1366]: May 22 10:11:00 volumio startx[1366]: waiting for X server to shut down May 22 10:11:00 volumio startx[1367]: (II) Server terminated successfully (0). Closing log file. May 22 10:11:01 volumio startx[1366]: xinit: unexpected signal 15 May 22 10:11:01 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE May 22 10:11:01 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. May 22 10:11:01 volumio systemd[1]: Stopped volumio-kiosk.service - Start Volumio Kiosk. May 22 10:11:01 volumio systemd[1]: volumio-kiosk.service: Consumed 16.328s CPU time. May 22 10:11:01 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. May 22 10:11:01 volumio sudo[3472]: pam_unix(sudo:session): session closed for user root May 22 10:11:01 volumio sudo[3480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable volumio-kiosk.service May 22 10:11:01 volumio sudo[3480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 22 10:11:01 volumio systemd[1]: Reloading. May 22 10:11:01 volumio systemd-gpt-auto-generator[3507]: EFI loader partition unknown, exiting. May 22 10:11:01 volumio systemd-gpt-auto-generator[3507]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) May 22 10:11:01 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 10:11:01 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 22 10:11:01 volumio sudo[3480]: pam_unix(sudo:session): session closed for user root May 22 10:11:01 volumio volumio[1264]: info: enable volumio-kiosk service success May 22 10:11:01 volumio startx[3516]: X.Org X Server 1.21.1.7 May 22 10:11:01 volumio startx[3516]: X Protocol Version 11, Revision 0 May 22 10:11:01 volumio startx[3516]: Current Operating System: Linux volumio 6.12.29-volumio #2 SMP PREEMPT_DYNAMIC Wed May 21 14:33:16 BST 2025 x86_64 May 22 10:11:01 volumio startx[3516]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=c897e988-5418-479f-888e-55c980ff60ee bootpart=UUID=32D6-CFD4 datapart=UUID=94bf464c-ec01-46cb-8417-70596a948bad hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no May 22 10:11:01 volumio startx[3516]: xorg-server 2:21.1.7-3+deb12u9 (https://www.debian.org/support) May 22 10:11:01 volumio startx[3516]: Current version of pixman: 0.42.2 May 22 10:11:01 volumio startx[3516]: Before reporting problems, check http://wiki.x.org May 22 10:11:01 volumio startx[3516]: to make sure that you have the latest version. May 22 10:11:01 volumio startx[3516]: Markers: (--) probed, (**) from config file, (==) default setting, May 22 10:11:01 volumio startx[3516]: (++) from command line, (!!) notice, (II) informational, May 22 10:11:01 volumio startx[3516]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 22 10:11:01 volumio startx[3516]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu May 22 10:11:01 2025 May 22 10:11:01 volumio startx[3516]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 22 10:11:01 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 10:11:02 volumio volumio[1264]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 22 10:11:02 volumio volumio[1264]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 22 10:11:02 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::volumioGetVisibleSources May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:03 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 10:11:03 volumio volumio[1264]: info: Received Get System Info May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:11:03 volumio volumio[1264]: info: Discovery: Getting this device information May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:03 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:03 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:03 volumio volumio[1264]: info: Listing playlists May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 10:11:03 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:03 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 10:11:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 10:11:04 volumio volumio[1264]: info: Received Get System Info May 22 10:11:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:11:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:11:04 volumio volumio[1264]: info: Discovery: Getting this device information May 22 10:11:04 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:04 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:04 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:11:05 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 10:11:05 volumio volumio[1264]: info: Received Get System Info May 22 10:11:05 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:11:05 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:11:05 volumio volumio[1264]: info: Discovery: Getting this device information May 22 10:11:05 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:05 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:05 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 10:11:09 volumio volumio[1264]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 10:11:09 volumio volumio[1264]: info: Received Get System Version May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 10:11:09 volumio volumio[1264]: info: Received Get System Info May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:11:09 volumio volumio[1264]: info: Discovery: Getting this device information May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:09 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:09 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:11:12 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 10:11:19 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:19 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 10:11:21 volumio volumio[1264]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 10:11:21 volumio volumio[1264]: info: Received Get System Version May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 10:11:21 volumio volumio[1264]: info: Received Get System Info May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 10:11:21 volumio volumio[1264]: info: Discovery: Getting this device information May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:11:21 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 22 10:11:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 10:11:21 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 22 10:11:24 volumio sshd[3801]: Connection closed by 192.168.1.188 port 53370 [preauth] May 22 10:11:36 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 22 10:11:36 volumio dbus-daemon[991]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.48' (uid=0 pid=3820 comm="timedatectl show --property=NTPSynchronized --valu") May 22 10:11:36 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 22 10:11:36 volumio dbus-daemon[991]: [system] Successfully activated service 'org.freedesktop.timedate1' May 22 10:11:36 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 22 10:11:36 volumio setdatetime-helper.sh[3819]: Time is already synchronized. May 22 10:11:36 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 22 10:11:36 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 22 10:11:41 volumio volumio[1264]: verbose: New Socket.io Connection to 192.168.1.250:3000 from 192.168.1.147 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 22 10:12:07 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 22 10:12:13 volumio sshd[3868]: Accepted password for volumio from 192.168.1.188 port 45196 ssh2 May 22 10:12:13 volumio sshd[3868]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) May 22 10:12:13 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. May 22 10:12:13 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... May 22 10:12:13 volumio systemd-logind[994]: New session 1 of user volumio. May 22 10:12:13 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. May 22 10:12:13 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... May 22 10:12:13 volumio (systemd)[3874]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) May 22 10:12:13 volumio systemd[3874]: Queued start job for default target default.target. May 22 10:12:13 volumio systemd[3874]: Created slice app.slice - User Application Slice. May 22 10:12:13 volumio systemd[3874]: Reached target paths.target - Paths. May 22 10:12:13 volumio systemd[3874]: Reached target timers.target - Timers. May 22 10:12:13 volumio systemd[3874]: Starting dbus.socket - D-Bus User Message Bus Socket... May 22 10:12:13 volumio systemd[3874]: Listening on dirmngr.socket - GnuPG network certificate management daemon. May 22 10:12:13 volumio systemd[3874]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). May 22 10:12:13 volumio systemd[3874]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). May 22 10:12:13 volumio systemd[3874]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). May 22 10:12:13 volumio systemd[3874]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. May 22 10:12:13 volumio systemd[3874]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 22 10:12:13 volumio systemd[3874]: Reached target sockets.target - Sockets. May 22 10:12:13 volumio systemd[3874]: Reached target basic.target - Basic System. May 22 10:12:13 volumio systemd[3874]: Reached target default.target - Main User Target. May 22 10:12:13 volumio systemd[3874]: Startup finished in 51ms. May 22 10:12:13 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. May 22 10:12:13 volumio systemd[1]: Started session-1.scope - Session 1 of User volumio. May 22 10:12:13 volumio sshd[3868]: pam_env(sshd:session): deprecated reading of user environment enabled May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:12:35 volumio volumio[1264]: info: Retrieving Cloud Streaming UI May 22 10:12:35 volumio volumio[1264]: info: Getting Tidal Cloud Configuration May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:12:35 volumio volumio[1264]: info: Getting Qobuz Cloud Configuration May 22 10:12:35 volumio volumio[1264]: info: Asking plugin for UI Config May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:12:35 volumio volumio[1264]: info: Getting Spotify Cloud Configuration May 22 10:12:35 volumio volumio[1264]: info: Asking plugin for UI Config May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:12:35 volumio volumio[1264]: info: Saving Spotify Acccount May 22 10:12:35 volumio volumio[1264]: info: Got Tidal Cloud Configuration May 22 10:12:35 volumio volumio[1264]: info: Got it May 22 10:12:35 volumio volumio[1264]: info: Got it May 22 10:12:35 volumio volumio[1264]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 10:12:35 volumio volumio[1264]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::volumioGetBrowseSources May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::volumioGetBrowseSources May 22 10:12:35 volumio volumio[1264]: info: CoreCommandRouter::volumioGetBrowseSources May 22 10:12:36 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 10:12:36 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 22 10:12:38 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 22 10:12:38 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 22 10:12:38 volumio volumio[1264]: info: CoreCommandRouter::volumioGetQueue May 22 10:12:38 volumio volumio[1264]: info: CoreStateMachine::getQueue May 22 10:12:38 volumio volumio[1264]: info: CorePlayQueue::getQueue May 22 10:12:40 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 10:12:40 volumio volumio[1264]: info: Preload queue cleared May 22 10:12:44 volumio volumio[1264]: Searching plugin music_service/webradio May 22 10:12:44 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 22 10:12:44 volumio volumio[1264]: Use XMLElement.setAttribute instead May 22 10:12:44 volumio volumio[1264]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 10:12:44 volumio volumio[1264]: TypeError: children[i].attr(...).value is not a function May 22 10:12:44 volumio volumio[1264]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 22 10:12:44 volumio volumio[1264]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 22 10:12:44 volumio volumio[1264]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 10:12:44 volumio sudo[3976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-22 10:11' May 22 10:12:44 volumio sudo[3976]: 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="a9e1adf9740e24b1b1a392b34b6acf37064d0420" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 14:28:31 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="244a93a40b2e4c18f4124fa55cfb0ef0"