Apr 13 14:06:00 volumio-pi4-1 systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 13 14:06:00 volumio-pi4-1 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 13 14:06:00 volumio-pi4-1 systemd[1]: Reached target graphical.target - Graphical Interface. Apr 13 14:06:00 volumio-pi4-1 ntpd[962]: IO: Listen normally on 3 eth0 192.168.1.221:123 Apr 13 14:06:00 volumio-pi4-1 ntpd[962]: IO: new interface(s) found: waking up resolver Apr 13 14:06:00 volumio-pi4-1 ntpd[962]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 14:06:01 volumio-pi4-1 ntpd[962]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Apr 13 14:06:01 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 193.136.164.4 Apr 13 14:06:01 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 162.159.200.123 Apr 13 14:06:01 volumio-pi4-1 ntpd[962]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Apr 13 14:06:01 volumio-pi4-1 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 13 14:06:01 volumio-pi4-1 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 13 14:06:01 volumio-pi4-1 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 13 14:06:01 volumio-pi4-1 systemd[1]: Startup finished in 15.415s (kernel) + 15.142s (userspace) = 30.557s. Apr 13 14:06:01 volumio-pi4-1 sudo[1364]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:01 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: Regdomain already correct: PT Apr 13 14:06:01 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Apr 13 14:06:01 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Apr 13 14:06:01 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Apr 13 14:06:01 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:06:01 volumio-pi4-1 volumio[1249]: info: ----- Volumio3 ---- Apr 13 14:06:01 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:06:01 volumio-pi4-1 ntpd[962]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 14:06:02 volumio-pi4-1 volumio[1249]: info: ----- System startup ---- Apr 13 14:06:02 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 91.209.16.78 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 185.228.163.139 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 2001:690:2100:14::1 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 2001:470:1f21:e2:1::32 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 2606:4700:f1::1 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 2001:ba0:252:ee00::123 Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Apr 13 14:06:02 volumio-pi4-1 sudo[1427]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 13 14:06:02 volumio-pi4-1 sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 13 14:06:02 volumio-pi4-1 sudo[1427]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:02 volumio-pi4-1 sudo[1430]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 13 14:06:02 volumio-pi4-1 sudo[1430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 13 14:06:02 volumio-pi4-1 sudo[1430]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:02 volumio-pi4-1 wpa_supplicant[1433]: Successfully initialized wpa_supplicant Apr 13 14:06:02 volumio-pi4-1 wpa_supplicant[1433]: nl80211: kernel reports: Registration to specific type not supported Apr 13 14:06:02 volumio-pi4-1 wpa_supplicant[1436]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 13 14:06:02 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 4481ms Apr 13 14:06:02 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Apr 13 14:06:02 volumio-pi4-1 volumio-remote-updater[759]: [2026-04-13 14:06:02] [connect] Successful connection Apr 13 14:06:02 volumio-pi4-1 wireless.js[763]: WIRELESS.JS - INFO: Notified systemd about wireless ready Apr 13 14:06:02 volumio-pi4-1 volumio[1249]: info: MYVOLUMIO Environment detected Apr 13 14:06:02 volumio-pi4-1 ntpd[962]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 14:06:03 volumio-pi4-1 ntpd[962]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 13 14:06:03 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 194.117.47.44 Apr 13 14:06:03 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 162.159.200.1 Apr 13 14:06:03 volumio-pi4-1 ntpd[962]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Plugin folders cleanup Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning into folder /volumio/app/plugins/ Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category audio_interface Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category miscellanea Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category music_service Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category plugins.json Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category system_controller Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category user_interface Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning into folder /data/plugins/ Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category music_service Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category system_hardware Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Scanning category user_interface Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Plugin folders cleanup completed Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: ----- Core plugins startup ---- Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Loading plugins from folder /volumio/app/plugins/ Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Adding plugin upnp to MyMusic Plugins Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Loading plugins from folder /data/plugins/ Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Adding plugin rtlsdr_radio to MyMusic Plugins Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Loading plugin "system"... Apr 13 14:06:03 volumio-pi4-1 volumio[1249]: info: Loading plugin "appearance"... Apr 13 14:06:03 volumio-pi4-1 ntpd[962]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 13 14:06:04 volumio-pi4-1 ntpd[962]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 13 14:06:04 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 193.136.152.71 Apr 13 14:06:04 volumio-pi4-1 ntpd[962]: DNS: Pool taking: 193.136.152.72 Apr 13 14:06:04 volumio-pi4-1 ntpd[962]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Loading plugin "network"... Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Refreshing Cached IP Addresses Apr 13 14:06:04 volumio-pi4-1 sudo[1460]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 14:06:04 volumio-pi4-1 sudo[1460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Loading plugin "services"... Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Loading plugin "volumio5onboarding"... Apr 13 14:06:04 volumio-pi4-1 sudo[1460]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Loading plugin "alsa_controller"... Apr 13 14:06:04 volumio-pi4-1 sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 14:06:04 volumio-pi4-1 sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:04 volumio-pi4-1 sudo[1472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 13 14:06:04 volumio-pi4-1 sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:04 volumio-pi4-1 sudo[1461]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:04 volumio-pi4-1 sudo[1472]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Loading plugin "wizard"... Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Loading plugin "networkfs"... Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Starting Udev Watcher for removable devices Apr 13 14:06:04 volumio-pi4-1 sudo[1493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=GREENAS,password=2030JLmf?,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft '//192.168.1.232/YT GAIN' /mnt/NAS/GREENAS Apr 13 14:06:04 volumio-pi4-1 sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Ignoring mount for partition: boot Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Ignoring mount for partition: volumio Apr 13 14:06:04 volumio-pi4-1 volumio[1249]: info: Ignoring mount for partition: volumio_data Apr 13 14:06:05 volumio-pi4-1 volumio[1249]: info: Mounting Device PI_4 Apr 13 14:06:05 volumio-pi4-1 kernel: netfs: FS-Cache loaded Apr 13 14:06:05 volumio-pi4-1 sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PI_4 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 13 14:06:05 volumio-pi4-1 sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:05 volumio-pi4-1 kernel: Key type cifs.spnego registered Apr 13 14:06:05 volumio-pi4-1 kernel: Key type cifs.idmap registered Apr 13 14:06:05 volumio-pi4-1 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 13 14:06:05 volumio-pi4-1 kernel: CIFS: Attempting to mount //192.168.1.232/YT GAIN Apr 13 14:06:05 volumio-pi4-1 sudo[1493]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:05 volumio-pi4-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Apr 13 14:06:05 volumio-pi4-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 14:06:05 volumio-pi4-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 14:06:05 volumio-pi4-1 upmpdcli[1538]: Could not open config: /tmp/upmpdcli.conf Apr 13 14:06:05 volumio-pi4-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 13 14:06:05 volumio-pi4-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 13 14:06:06 volumio-pi4-1 ntfs-3g[1540]: Version 2022.10.3 integrated FUSE 28 Apr 13 14:06:06 volumio-pi4-1 ntfs-3g[1540]: Mounted /dev/sda1 (Read-Write, label "PI 4", NTFS 3.1) Apr 13 14:06:06 volumio-pi4-1 ntfs-3g[1540]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 13 14:06:06 volumio-pi4-1 ntfs-3g[1540]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 13 14:06:06 volumio-pi4-1 ntfs-3g[1540]: Global ownership and permissions enforced, configuration type 7 Apr 13 14:06:06 volumio-pi4-1 sudo[1505]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: Loading plugin "volumio_command_line_client"... Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: Loading plugin "upnp"... Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: [1776085566459] Starting Upmpd Daemon Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: Loading plugin "my_music"... Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: Loading plugin "mpd"... Apr 13 14:06:06 volumio-pi4-1 volumio[1249]: info: Loading plugin "upnp_browser"... Apr 13 14:06:40 volumio-pi4-1 ntpd[962]: CLOCK: time stepped by 32.753561 Apr 13 14:06:40 volumio-pi4-1 ntpd[962]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 13 14:06:40 volumio-pi4-1 volumio[1249]: info: Starting UPNP Browser Apr 13 14:06:40 volumio-pi4-1 volumio[1249]: info: Loading plugin "alarm-clock"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Loading plugin "airplay_emulation"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Starting Shairport Sync Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Loading plugin "last_100"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Loading plugin "webradio"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Loading plugin "i2s_dacs"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Loading plugin "volumiodiscovery"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: *** WARNING *** For more information see Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 14:06:41 volumio-pi4-1 node[1249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: *** WARNING *** For more information see Apr 13 14:06:41 volumio-pi4-1 node[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 14:06:41 volumio-pi4-1 node[1249]: *** WARNING *** For more information see Apr 13 14:06:41 volumio-pi4-1 node[1249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 14:06:41 volumio-pi4-1 node[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 14:06:41 volumio-pi4-1 node[1249]: *** WARNING *** For more information see Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Discovery: Started advertising with name: VOLUMIO PI_4-1 Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: info: Loading plugin "gpio-buttons"... Apr 13 14:06:41 volumio-pi4-1 volumio[1249]: gpiox_arm32 V2.2.1 Apr 13 14:06:42 volumio-pi4-1 volumio[1249]: info: gpio-buttons - GPIO-Buttons initialized Apr 13 14:06:42 volumio-pi4-1 volumio[1249]: info: Loading plugin "now_playing"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "outputs"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "albumart"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Plugin example_plugin is not enabled Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "inputs"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "updater_comm"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Plugin mpdemulation is not enabled Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "rest_api"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "websocket"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Starting Socket.io Server version 1.7.4 Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Plugin minidlna is not enabled Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "motherearthradio"... Apr 13 14:06:44 volumio-pi4-1 volumio[1249]: info: Loading plugin "radio_paradise"... Apr 13 14:06:45 volumio-pi4-1 volumio[1543]: Forking 3 albumart workers Apr 13 14:06:45 volumio-pi4-1 volumio[1249]: info: Applying required configuration parameters for plugin radio_paradise Apr 13 14:06:45 volumio-pi4-1 volumio[1249]: info: [1776085605687] [RadioParadise] API delay: 5 Apr 13 14:06:45 volumio-pi4-1 volumio[1249]: info: Loading plugin "rtlsdr_radio"... Apr 13 14:06:49 volumio-pi4-1 volumio[1555]: Starting albumart workers Apr 13 14:06:50 volumio-pi4-1 volumio-remote-updater[759]: [2026-04-13 14:06:50] [connect] Successful connection Apr 13 14:06:50 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Loaded FM region data Apr 13 14:06:50 volumio-pi4-1 volumio[1249]: info: Plugin peppymeterbasic is not enabled Apr 13 14:06:50 volumio-pi4-1 volumio[1249]: info: Loading plugin "touch_display"... Apr 13 14:06:50 volumio-pi4-1 volumio[1553]: Starting albumart workers Apr 13 14:06:50 volumio-pi4-1 volumio[1554]: Starting albumart workers Apr 13 14:06:50 volumio-pi4-1 systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 13 14:06:51 volumio-pi4-1 volumio[1249]: info: Applying required configuration parameters for plugin touch_display Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: Loading i18n strings for locale en Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: Updating browse sources language Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::initPlayerControls Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: Express server listening on port 3000 Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: [Metrics] WebUI: 18s 962.21ms Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::resetVolumioState Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::getcurrentVolume Apr 13 14:06:52 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 14:06:53 volumio-pi4-1 sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 14:06:53 volumio-pi4-1 sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:53 volumio-pi4-1 sudo[1616]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:53 volumio-pi4-1 sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 14:06:53 volumio-pi4-1 sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:53 volumio-pi4-1 sudo[1617]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:53 volumio-pi4-1 volumio[1249]: info: Volumio Network Manager: Network status updated: 1 Apr 13 14:06:53 volumio-pi4-1 volumio[1249]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 13 14:06:53 volumio-pi4-1 volumio[1249]: wlan0 Interface doesn't support scanning : Device or resource busy Apr 13 14:06:53 volumio-pi4-1 volumio[1249]: info: Cannot use regular scanning, forcing with ap-force Apr 13 14:06:53 volumio-pi4-1 sudo[1629]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 13 14:06:53 volumio-pi4-1 sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:53 volumio-pi4-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 13 14:06:53 volumio-pi4-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 14:06:53 volumio-pi4-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 13 14:06:53 volumio-pi4-1 upmpdcli[1631]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.221 port 49152 IPV6 port 49152 Apr 13 14:06:53 volumio-pi4-1 upmpdcli[1631]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Apr 13 14:06:53 volumio-pi4-1 upmpdcli[1631]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Apr 13 14:06:53 volumio-pi4-1 upmpdcli[1631]: :2:../src/main.cxx:732::MPD connection failed Apr 13 14:06:54 volumio-pi4-1 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 13 14:06:55 volumio-pi4-1 upmpdcli[1631]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Apr 13 14:06:55 volumio-pi4-1 upmpdcli[1631]: :2:../src/main.cxx:732::MPD connection failed Apr 13 14:06:55 volumio-pi4-1 sudo[1629]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 1 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: VolumeController:: Volume=79 Mute =false Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::pushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioPushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::updateTrackBlock Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrackBlock Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::setRepeat false single undefined Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::pushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioPushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::setRandom false Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::pushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioPushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: Setting Device type: Raspberry PI Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 3 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: Completed loading Core Plugins Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: Preparing to generate the ALSA configuration file Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: VolumeController:: Volume=79 Mute =false Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::pushState Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:06:56 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioPushState Apr 13 14:06:56 volumio-pi4-1 sudo[1657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 13 14:06:56 volumio-pi4-1 sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:57 volumio-pi4-1 sudo[1657]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Discovery: adding f10fb62e-3e36-4add-a28f-7c73589b496d Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Discovery: Found device VOLUMIO PI_4-1 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Discovery: this is already registered, f10fb62e-3e36-4add-a28f-7c73589b496d Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Discovery: Found device VOLUMIO PI_4-1 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Upmpdcli Daemon Started Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Asound.conf file unchanged, so no further update is needed Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Output device has changed, restarting MPD Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Output device has changed, restarting Shairport Sync Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:06:57 volumio-pi4-1 sudo[1662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 14:06:57 volumio-pi4-1 sudo[1662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:57 volumio-pi4-1 sudo[1660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 14:06:57 volumio-pi4-1 sudo[1660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: ___________ START PLUGINS ___________ Apr 13 14:06:57 volumio-pi4-1 sudo[1660]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: ControllerMpd::onStart: Initializing MPD Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Creating MPD Configuration file Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 13 14:06:57 volumio-pi4-1 sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 13 14:06:57 volumio-pi4-1 sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: [1776085617607] CoreMusicLibrary::Adding element Media Servers Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: UPNP Browser: Client initialized successfully Apr 13 14:06:57 volumio-pi4-1 sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 14:06:57 volumio-pi4-1 sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:57 volumio-pi4-1 sudo[1673]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:57 volumio-pi4-1 sudo[1675]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 13 14:06:57 volumio-pi4-1 sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:06:57 volumio-pi4-1 sudo[1679]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 13 14:06:57 volumio-pi4-1 sudo[1675]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:57 volumio-pi4-1 sudo[1676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 14:06:57 volumio-pi4-1 sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 13 14:06:57 volumio-pi4-1 sudo[1671]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:57 volumio-pi4-1 systemd[1]: mpd.service: Deactivated successfully. Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 13 14:06:57 volumio-pi4-1 systemd[1]: mpd.socket: Deactivated successfully. Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:06:57 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 13 14:06:57 volumio-pi4-1 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [1776085618074] CoreMusicLibrary::Adding element Last_100 Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [1776085618090] CoreMusicLibrary::Adding element Webradio Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: Initializing BBC Radios Apr 13 14:06:58 volumio-pi4-1 sudo[1701]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 13 14:06:58 volumio-pi4-1 sudo[1701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 13 14:06:58 volumio-pi4-1 sudo[1717]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 13 14:06:58 volumio-pi4-1 sudo[1701]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:58 volumio-pi4-1 volumio5-onboarding[1683]: time=2026-04-13T14:06:58.224+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: gpio-buttons - Initializing GPIO triggers... Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: gpio-buttons - ✅ GPIO5 configured for shutdown Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [now-playing] ConfigUpdater: config is up to date. Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [1776085618595] CoreMusicLibrary::Adding element Mother Earth Radio Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: Cannot find translation for source Mother Earth Radio Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [MER] Plugin started (SSE mode) Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [1776085618621] CoreMusicLibrary::Adding element Radio Paradise Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: Cannot find translation for source Mother Earth Radio Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: Cannot find translation for source Radio Paradise Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Starting plugin Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Loaded i18n strings for language: en Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: Loading i18n strings for locale en Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 14:06:58 volumio-pi4-1 volumio[1249]: info: Volumio Calling Home Apr 13 14:06:59 volumio-pi4-1 sudo[1732]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Apr 13 14:06:59 volumio-pi4-1 sudo[1732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:59 volumio-pi4-1 sudo[1732]: pam_unix(sudo:session): session closed for user root Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: gpio-buttons - GPIO-Buttons started Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] snd-aloop already loaded Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] No stations database found, creating v2 Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Database loaded at: 2026-04-13T13:06:59.324Z Apr 13 14:06:59 volumio-pi4-1 sudo[1742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 13 14:06:59 volumio-pi4-1 sudo[1742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Loading blocklist from: /data/plugins/music_service/rtlsdr_radio/blocklist.json Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Got 21 phrases from file Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Updated metadata blocklist Apr 13 14:06:59 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Metadata module now has 21 phrases Apr 13 14:06:59 volumio-pi4-1 sudo[1747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 13 14:06:59 volumio-pi4-1 sudo[1747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:59 volumio-pi4-1 sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 13 14:06:59 volumio-pi4-1 sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:06:59 volumio-pi4-1 systemd[1]: Reloading. Apr 13 14:06:59 volumio-pi4-1 sudo[1742]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Management server started on port 3456 Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: info: [1776085621129] CoreMusicLibrary::Adding element FM/DAB Radio Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: Cannot find translation for source Mother Earth Radio Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: Cannot find translation for source Radio Paradise Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: Cannot find translation for source FM/DAB Radio Apr 13 14:07:01 volumio-pi4-1 volumio[1249]: info: [RTL-SDR Radio] Plugin started successfully Apr 13 14:07:01 volumio-pi4-1 systemd[1]: Reloading. Apr 13 14:07:01 volumio-pi4-1 sudo[1747]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:01 volumio-pi4-1 upmpdcli[1631]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Apr 13 14:07:01 volumio-pi4-1 upmpdcli[1631]: :2:../src/main.cxx:732::MPD connection failed Apr 13 14:07:02 volumio-pi4-1 mpd[1718]: 2026-04-13T14:07:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 13 14:07:02 volumio-pi4-1 sudo[1745]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:03 volumio-pi4-1 systemd[1]: Started mpd.service - Music Player Daemon. Apr 13 14:07:03 volumio-pi4-1 sudo[1676]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:03 volumio-pi4-1 sudo[1662]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:03 volumio-pi4-1 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 13 14:07:03 volumio-pi4-1 volumio[1249]: info: [now-playing] App is listening on port 4004. Apr 13 14:07:03 volumio-pi4-1 volumio[1249]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Apr 13 14:07:03 volumio-pi4-1 volumio[1249]: info: touch_display: No backlight interface detected. Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: info: touch_display: systemctl daemon-reload succeeded. Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: info: MPD Permissions set Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: info: MPD Permissions set Apr 13 14:07:04 volumio-pi4-1 sudo[1801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 13 14:07:04 volumio-pi4-1 sudo[1801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:04 volumio-pi4-1 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Apr 13 14:07:04 volumio-pi4-1 sudo[1801]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Apr 13 14:07:04 volumio-pi4-1 volumio[1249]: info: Volumio called home Apr 13 14:07:04 volumio-pi4-1 sudo[1805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Apr 13 14:07:04 volumio-pi4-1 sudo[1805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:04 volumio-pi4-1 startx[1839]: X.Org X Server 1.21.1.7 Apr 13 14:07:04 volumio-pi4-1 startx[1839]: X Protocol Version 11, Revision 0 Apr 13 14:07:04 volumio-pi4-1 startx[1839]: Current Operating System: Linux volumio-pi4-1 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Apr 13 14:07:04 volumio-pi4-1 startx[1839]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:1920x1080M@60D,margin_left=48,margin_right=48,margin_top=48,margin_bottom=48 numa=fake=1 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=D8:3A:DD:67:B6:41 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=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 13 14:07:04 volumio-pi4-1 startx[1839]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Apr 13 14:07:04 volumio-pi4-1 startx[1839]: Current version of pixman: 0.44.0 Apr 13 14:07:04 volumio-pi4-1 startx[1839]: Before reporting problems, check http://wiki.x.org Apr 13 14:07:04 volumio-pi4-1 startx[1839]: to make sure that you have the latest version. Apr 13 14:07:04 volumio-pi4-1 startx[1839]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 13 14:07:04 volumio-pi4-1 startx[1839]: (++) from command line, (!!) notice, (II) informational, Apr 13 14:07:04 volumio-pi4-1 startx[1839]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 13 14:07:04 volumio-pi4-1 startx[1839]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Apr 13 14:07:04 2026 Apr 13 14:07:04 volumio-pi4-1 startx[1839]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 13 14:07:04 volumio-pi4-1 startx[1839]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 13 14:07:04 volumio-pi4-1 sudo[1805]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:05 volumio-pi4-1 systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 13 14:07:05 volumio-pi4-1 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: Received Get System Info Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: Discovery: Getting this device information Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:07:05 volumio-pi4-1 volumio5-onboarding[1683]: time=2026-04-13T14:07:05.095+01:00 level=INFO msg="system info for b803ea9730469d566cbaea0f49ed774c" deviceName="VOLUMIO PI_4-1" deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 13 14:07:05 volumio-pi4-1 volumio5-onboarding[1683]: time=2026-04-13T14:07:05.143+01:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 13 14:07:05 volumio-pi4-1 volumio-remote-updater[759]: [2026-04-13 14:07:05] [connect] Successful connection Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: error: MPD error: The expression evaluated to a falsy value: Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: assert.ok(self.idling) Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: error: The expression evaluated to a falsy value: Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: assert.ok(self.idling) Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 8 Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: touch_display: Volumio Kiosk started. Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: Completed starting Core Plugins Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: ----- MyVolumio plugins startup ---- Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: ------------------------------------------- Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: MPD running with PID1718 Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: ,establishing connection Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:05 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: Received Get System Info Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: Discovery: Getting this device information Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: error: updateQueue error: null Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: touch_display: IframeKeyboardBridge extension installed successfully Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: touch_display: X display number found: 0 Apr 13 14:07:06 volumio-pi4-1 sudo[1884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 13 14:07:06 volumio-pi4-1 sudo[1884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:06 volumio-pi4-1 sudo[1884]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 13 14:07:06 volumio-pi4-1 sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 13 14:07:06 volumio-pi4-1 sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: error: updateQueue error: null Apr 13 14:07:06 volumio-pi4-1 sudo[1887]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 14:07:06 volumio-pi4-1 volumio[1249]: info: Starting Shairport Sync Apr 13 14:07:07 volumio-pi4-1 volumio[1249]: info: Starting Shairport Sync Apr 13 14:07:07 volumio-pi4-1 sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 14:07:07 volumio-pi4-1 sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:07 volumio-pi4-1 volumio[1249]: info: Starting Shairport Sync Apr 13 14:07:07 volumio-pi4-1 sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 14:07:07 volumio-pi4-1 sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 13 14:07:07 volumio-pi4-1 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: shairport-sync.service: Consumed 1.759s CPU time. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 14:07:07 volumio-pi4-1 sudo[1934]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:07 volumio-pi4-1 sudo[1941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 14:07:07 volumio-pi4-1 sudo[1941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 13 14:07:07 volumio-pi4-1 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 13 14:07:07 volumio-pi4-1 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 14:07:07 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 10 Apr 13 14:07:07 volumio-pi4-1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 13 14:07:07 volumio-pi4-1 sudo[1916]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:07 volumio-pi4-1 sudo[1941]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:07 volumio-pi4-1 volumio[1249]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 13 14:07:07 volumio-pi4-1 volumio[1249]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 13 14:07:07 volumio-pi4-1 volumio[1249]: info: touch_display: X display number found: 0 Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: info: Shairport-Sync Started Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: Error adding Membership: Error: addMembership EINVAL Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: info: Shairport-Sync Started Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: info: Shairport-Sync Started Apr 13 14:07:08 volumio-pi4-1 volumio-remote-updater[759]: [2026-04-13 14:07:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1776085625 101 Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 11 Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 13 14:07:08 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to 192.168.1.221 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 12 Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: Listing playlists Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetQueue Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::getQueue Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getQueue Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:09 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:09 volumio-pi4-1 upmpdcli[2038]: writing RSA key Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: Discovery: Getting this device information Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: error: MyVolumio Plugin failed to start in a timely fashion Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: [Metrics] CommandRouter: 51s 230.26ms Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumiosetStartupVolume Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: VolumeController:: Setting startup Volume 30 Apr 13 14:07:25 volumio-pi4-1 volumio[1249]: info: VolumeController::SetAlsaVolume30 Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::Close All Modals sent Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::Close All Modals sent Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CoreStateMachine::pushState Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 14:07:26 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioPushState Apr 13 14:07:35 volumio-pi4-1 volumio5-onboarding[1683]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 13 14:07:35 volumio-pi4-1 systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 13 14:07:35 volumio-pi4-1 systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 13 14:07:35 volumio-pi4-1 systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 13 14:07:35 volumio-pi4-1 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 13 14:07:35 volumio-pi4-1 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 13 14:07:35 volumio-pi4-1 volumio5-onboarding[2154]: time=2026-04-13T14:07:35.538+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 14:07:35 volumio-pi4-1 volumio[1249]: info: BOOT COMPLETED Apr 13 14:07:40 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Received Get System Info Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Discovery: Getting this device information Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::volumioGetState Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: CorePlayQueue::getTrack 0 Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 14:07:41 volumio-pi4-1 volumio5-onboarding[2154]: time=2026-04-13T14:07:41.276+01:00 level=INFO msg="system info for b803ea9730469d566cbaea0f49ed774c" deviceName="VOLUMIO PI_4-1" deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 13 14:07:41 volumio-pi4-1 volumio5-onboarding[2154]: time=2026-04-13T14:07:41.293+01:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin bluetooth to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin multiroom to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin metavolumio to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin cd_controller to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 13 14:07:41 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: Starting MyVolumio Remote Streaming Endpoints Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: MyVolumio login type: Token Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 13 14:07:43 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 13 14:07:44 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 13 14:07:44 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 13 14:07:44 volumio-pi4-1 volumio[1249]: info: Streaming services startup Apr 13 14:07:44 volumio-pi4-1 volumio[1249]: info: Starting Streaming Daemon Apr 13 14:07:44 volumio-pi4-1 volumio[1249]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 13 14:07:44 volumio-pi4-1 sudo[2187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 13 14:07:44 volumio-pi4-1 sudo[2187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 14:07:44 volumio-pi4-1 volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: at new NodeError (node:internal/errors:405:5) Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: at assert (node:internal/assert:14:11) Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: at internalConnectMultiple (node:net:1118:3) Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: at listOnTimeout (node:internal/timers:575:11) Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: at process.processTimers (node:internal/timers:514:7) { Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: code: 'ERR_INTERNAL_ASSERTION' Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: } Apr 13 14:07:45 volumio-pi4-1 volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 14:07:45 volumio-pi4-1 sudo[2187]: pam_unix(sudo:session): session closed for user root Apr 13 14:07:48 volumio-pi4-1 sudo[2207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-13 14:06' Apr 13 14:07:48 volumio-pi4-1 sudo[2207]: 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"