Mar 27 09:02:00 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: GB
Mar 27 09:02:00 volumio sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB
Mar 27 09:02:00 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:00 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: GB
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode)
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active
Mar 27 09:02:00 volumio sudo[1442]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Mar 27 09:02:00 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 09:02:00 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:00 volumio sudo[1445]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Mar 27 09:02:00 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 09:02:00 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:00 volumio wpa_supplicant[1448]: Successfully initialized wpa_supplicant
Mar 27 09:02:00 volumio wpa_supplicant[1448]: nl80211: kernel reports: Registration to specific type not supported
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3842ms
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable
Mar 27 09:02:00 volumio wireless.js[1115]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory
Mar 27 09:02:00 volumio wireless.js[1115]: WIRELESS.JS - INFO: Notified systemd about wireless ready
Mar 27 09:02:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Mar 27 09:02:00 volumio systemd[1]: Started wireless.service - Wireless Services.
Mar 27 09:02:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module.
Mar 27 09:02:00 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen...
Mar 27 09:02:00 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker.
Mar 27 09:02:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 419 (plymouthd).
Mar 27 09:02:00 volumio volumio-cpu-tweak[1470]: Setting RT Priority for mpd
Mar 27 09:02:00 volumio volumio-cpu-tweak[1484]: pid 35's current scheduling policy: SCHED_OTHER
Mar 27 09:02:00 volumio volumio-cpu-tweak[1484]: pid 35's current scheduling priority: 0
Mar 27 09:02:00 volumio volumio-cpu-tweak[1470]: Setting MPD Affinity
Mar 27 09:02:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 419 (plymouthd).
Mar 27 09:02:00 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen.
Mar 27 09:02:00 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up.
Mar 27 09:02:00 volumio systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10.
Mar 27 09:02:00 volumio systemd[1]: Reached target getty.target - Login Prompts.
Mar 27 09:02:02 volumio bash[1260]: setdatetime-helper: all HTTPS Date fallbacks failed
Mar 27 09:02:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Mar 27 09:02:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Mar 27 09:02:02 volumio volumio-cpu-tweak[1485]: pid 3's current affinity mask: f
Mar 27 09:02:02 volumio volumio-cpu-tweak[1470]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input
Mar 27 09:02:02 volumio volumio-cpu-tweak[1470]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative
Mar 27 09:02:02 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully.
Mar 27 09:02:02 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:02 volumio volumio[1468]: info: ----- Volumio3 ----
Mar 27 09:02:02 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:02 volumio volumio[1468]: info: ----- System startup ----
Mar 27 09:02:02 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:03 volumio volumio[1468]: info: MYVOLUMIO Environment detected
Mar 27 09:02:03 volumio volumio[1468]: info: Plugin folders cleanup
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning into folder /volumio/app/plugins/
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category audio_interface
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category miscellanea
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category music_service
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category plugins.json
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category system_controller
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category user_interface
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning into folder /data/plugins/
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category system_hardware
Mar 27 09:02:03 volumio volumio[1468]: info: Scanning category user_interface
Mar 27 09:02:03 volumio volumio[1468]: info: Plugin folders cleanup completed
Mar 27 09:02:03 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:03 volumio volumio[1468]: info: ----- Core plugins startup ----
Mar 27 09:02:03 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugins from folder /volumio/app/plugins/
Mar 27 09:02:03 volumio volumio[1468]: info: Adding plugin upnp to MyMusic Plugins
Mar 27 09:02:03 volumio volumio[1468]: info: Adding plugin airplay_emulation to MyMusic Plugins
Mar 27 09:02:03 volumio volumio[1468]: info: Adding plugin upnp_browser to MyMusic Plugins
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugins from folder /data/plugins/
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "system"...
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "appearance"...
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "network"...
Mar 27 09:02:03 volumio volumio[1468]: info: Refreshing Cached IP Addresses
Mar 27 09:02:03 volumio sudo[1510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 27 09:02:03 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:03 volumio sudo[1512]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 27 09:02:03 volumio sudo[1512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:03 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "services"...
Mar 27 09:02:03 volumio sudo[1512]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "volumio5onboarding"...
Mar 27 09:02:03 volumio sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 27 09:02:03 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "alsa_controller"...
Mar 27 09:02:03 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "wizard"...
Mar 27 09:02:03 volumio volumio[1468]: info: Loading plugin "networkfs"...
Mar 27 09:02:03 volumio volumio[1468]: info: Starting Udev Watcher for removable devices
Mar 27 09:02:03 volumio volumio[1468]: info: Mounting Device FA14-0D7F
Mar 27 09:02:03 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/FA14-0D7F -o noatime
Mar 27 09:02:03 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:03 volumio dhcpcd[1116]: eth0: leased 192.168.1.137 for 86400 seconds
Mar 27 09:02:03 volumio avahi-daemon[1094]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.137.
Mar 27 09:02:03 volumio avahi-daemon[1094]: New relevant interface eth0.IPv4 for mDNS.
Mar 27 09:02:03 volumio avahi-daemon[1094]: Registering new address record for 192.168.1.137 on eth0.IPv4.
Mar 27 09:02:03 volumio dhcpcd[1116]: eth0: adding route to 192.168.1.0/24
Mar 27 09:02:03 volumio dhcpcd[1116]: eth0: adding default route via 192.168.1.1
Mar 27 09:02:03 volumio systemd[1]: welcome.service: Deactivated successfully.
Mar 27 09:02:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Mar 27 09:02:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Mar 27 09:02:03 volumio sh[939]: eth0: leased 192.168.1.137 for 86400 seconds
Mar 27 09:02:03 volumio dhcpcd[962]: eth0: leased 192.168.1.137 for 86400 seconds
Mar 27 09:02:03 volumio sh[939]: eth0: adding route to 192.168.1.0/24
Mar 27 09:02:03 volumio sh[939]: eth0: adding default route via 192.168.1.1
Mar 27 09:02:03 volumio dhcpcd[962]: eth0: adding route to 192.168.1.0/24
Mar 27 09:02:03 volumio dhcpcd[962]: eth0: adding default route via 192.168.1.1
Mar 27 09:02:03 volumio sh[939]: forked to background, child pid 961
Mar 27 09:02:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Mar 27 09:02:03 volumio welcome[1572]: Resolved ip:[1] 192.168.1.137
Mar 27 09:02:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Mar 27 09:02:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Mar 27 09:02:03 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon.
Mar 27 09:02:03 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon...
Mar 27 09:02:03 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0.
Mar 27 09:02:03 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0...
Mar 27 09:02:03 volumio systemd[1]: welcome.service: Deactivated successfully.
Mar 27 09:02:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console.
Mar 27 09:02:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console...
Mar 27 09:02:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console...
Mar 27 09:02:03 volumio welcome[1620]: Resolved ip:[1] 192.168.1.137
Mar 27 09:02:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console.
Mar 27 09:02:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0.
Mar 27 09:02:03 volumio ifplugd(eth0)[1296]: client: ifup: interface eth0 already configured
Mar 27 09:02:03 volumio sh[1630]: eth0=eth0
Mar 27 09:02:04 volumio ifplugd(eth0)[1296]: Program executed successfully.
Mar 27 09:02:04 volumio ifplugd[1246]: Network Interface Plugging Daemon...start eth0...done.
Mar 27 09:02:04 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically.
Mar 27 09:02:04 volumio winbindd[1631]: [2026/03/27 09:02:04.047620, 0] ../../source3/winbindd/winbindd.c:1440(main)
Mar 27 09:02:04 volumio winbindd[1631]: winbindd version 4.17.8-Raspbian started.
Mar 27 09:02:04 volumio winbindd[1631]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Mar 27 09:02:04 volumio winbindd[1631]: [2026/03/27 09:02:04.055136, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache)
Mar 27 09:02:04 volumio winbindd[1631]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Mar 27 09:02:04 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon.
Mar 27 09:02:04 volumio kernel: exFAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Mar 27 09:02:04 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon...
Mar 27 09:02:04 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:04 volumio volumio[1468]: info: Ignoring mount for partition: boot
Mar 27 09:02:04 volumio volumio[1468]: info: Ignoring mount for partition: volumio
Mar 27 09:02:04 volumio volumio[1468]: info: Ignoring mount for partition: volumio_data
Mar 27 09:02:04 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 27 09:02:04 volumio volumio[1468]: info: Loading plugin "pi_screen_setup"...
Mar 27 09:02:04 volumio smbd[1642]: [2026/03/27 09:02:04.303089, 0] ../../source3/smbd/server.c:1741(main)
Mar 27 09:02:04 volumio smbd[1642]: smbd version 4.17.8-Raspbian started.
Mar 27 09:02:04 volumio smbd[1642]: Copyright Andrew Tridgell and the Samba Team 1992-2022
Mar 27 09:02:04 volumio systemd[1]: Started smbd.service - Samba SMB Daemon.
Mar 27 09:02:04 volumio systemd[1]: Reached target multi-user.target - Multi-User System.
Mar 27 09:02:04 volumio systemd[1]: Reached target graphical.target - Graphical Interface.
Mar 27 09:02:04 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Mar 27 09:02:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Mar 27 09:02:04 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Mar 27 09:02:04 volumio systemd[1]: Startup finished in 15.214s (kernel) + 13.433s (userspace) = 28.648s.
Mar 27 09:02:04 volumio volumio[1468]: info: Loading plugin "volumio_command_line_client"...
Mar 27 09:02:04 volumio volumio[1468]: info: Loading plugin "upnp"...
Mar 27 09:02:04 volumio volumio[1468]: info: [1774602124872] Starting Upmpd Daemon
Mar 27 09:02:04 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 27 09:02:04 volumio volumio[1468]: info: Loading plugin "my_music"...
Mar 27 09:02:04 volumio volumio[1468]: info: Loading plugin "mpd"...
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "upnp_browser"...
Mar 27 09:02:05 volumio volumio[1468]: info: Starting UPNP Browser
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "alarm-clock"...
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "airplay_emulation"...
Mar 27 09:02:05 volumio volumio[1468]: info: Starting Shairport Sync
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "last_100"...
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "webradio"...
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "i2s_dacs"...
Mar 27 09:02:05 volumio volumio[1468]: info: I2S DAC not set, start Auto-detection
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "volumiodiscovery"...
Mar 27 09:02:05 volumio volumio[1468]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Mar 27 09:02:05 volumio volumio[1468]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 27 09:02:05 volumio volumio[1468]: *** WARNING *** For more information see
Mar 27 09:02:05 volumio volumio[1468]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Mar 27 09:02:05 volumio volumio[1468]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 27 09:02:05 volumio volumio[1468]: *** WARNING *** For more information see
Mar 27 09:02:05 volumio node[1468]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Mar 27 09:02:05 volumio node[1468]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 27 09:02:05 volumio node[1468]: *** WARNING *** For more information see
Mar 27 09:02:05 volumio node[1468]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Mar 27 09:02:05 volumio node[1468]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 27 09:02:05 volumio node[1468]: *** WARNING *** For more information see
Mar 27 09:02:05 volumio volumio[1468]: info: Applying required configuration parameters for plugin volumiodiscovery
Mar 27 09:02:05 volumio volumio[1468]: info: Discovery: Started advertising with name: Volumio
Mar 27 09:02:05 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "outputs"...
Mar 27 09:02:05 volumio volumio[1468]: info: Loading plugin "albumart"...
Mar 27 09:02:05 volumio ntpd[1282]: IO: Listen normally on 3 eth0 192.168.1.137:123
Mar 27 09:02:05 volumio ntpd[1282]: IO: new interface(s) found: waking up resolver
Mar 27 09:02:05 volumio ntpd[1282]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Mar 27 09:02:05 volumio ntpd[1282]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Mar 27 09:02:05 volumio ntpd[1282]: DNS: Pool taking: 178.79.184.152
Mar 27 09:02:05 volumio ntpd[1282]: DNS: Pool taking: 176.58.127.131
Mar 27 09:02:05 volumio ntpd[1282]: DNS: Pool taking: 149.22.188.7
Mar 27 09:02:05 volumio ntpd[1282]: DNS: Pool taking: 178.79.150.226
Mar 27 09:02:05 volumio ntpd[1282]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8
Mar 27 09:02:06 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:06 volumio volumio-remote-updater[1112]: [2026-03-27 09:02:06] [connect] Successful connection
Mar 27 09:02:06 volumio ntpd[1282]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Mar 27 09:02:06 volumio ntpd[1282]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 194.213.3.203
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 93.93.131.118
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 176.58.109.199
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 178.79.138.215
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 2a0f:85c1:dd6::7b3a
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 2a10:e780:11:1::6d
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 2a05:d01c:692:1200:48ab:413a:c916:ca31
Mar 27 09:02:06 volumio ntpd[1282]: DNS: Pool taking: 2a06:a880:5:d3cc::123
Mar 27 09:02:06 volumio ntpd[1282]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Mar 27 09:02:07 volumio ntpd[1282]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Mar 27 09:02:07 volumio ntpd[1282]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Mar 27 09:02:07 volumio ntpd[1282]: DNS: Pool taking: 172.237.96.114
Mar 27 09:02:07 volumio ntpd[1282]: DNS: Pool taking: 176.58.124.166
Mar 27 09:02:07 volumio ntpd[1282]: DNS: Pool taking: 78.141.201.161
Mar 27 09:02:07 volumio ntpd[1282]: DNS: Pool taking: 162.159.200.123
Mar 27 09:02:07 volumio ntpd[1282]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Mar 27 09:02:08 volumio volumio[1468]: info: Plugin example_plugin is not enabled
Mar 27 09:02:08 volumio volumio[1468]: info: Loading plugin "inputs"...
Mar 27 09:02:08 volumio volumio[1468]: info: Loading plugin "updater_comm"...
Mar 27 09:02:08 volumio ntpd[1282]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Mar 27 09:02:08 volumio ntpd[1282]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Mar 27 09:02:08 volumio ntpd[1282]: DNS: Pool taking: 87.106.36.214
Mar 27 09:02:08 volumio ntpd[1282]: DNS: Pool taking: 217.114.59.66
Mar 27 09:02:08 volumio ntpd[1282]: DNS: Pool taking: 83.217.166.45
Mar 27 09:02:08 volumio ntpd[1282]: DNS: Pool taking: 85.199.214.99
Mar 27 09:02:08 volumio ntpd[1282]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Mar 27 09:02:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1.
Mar 27 09:02:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 27 09:02:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 27 09:02:09 volumio upmpdcli[1676]: Could not open config: /tmp/upmpdcli.conf
Mar 27 09:02:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 09:02:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Mar 27 09:02:11 volumio ntpd[1282]: CLOCK: time stepped by 0.316908
Mar 27 09:02:11 volumio ntpd[1282]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Mar 27 09:02:12 volumio volumio[1665]: Forking 3 albumart workers
Mar 27 09:02:12 volumio volumio[1468]: info: Plugin mpdemulation is not enabled
Mar 27 09:02:12 volumio volumio[1468]: info: Loading plugin "rest_api"...
Mar 27 09:02:12 volumio volumio[1468]: info: Loading plugin "websocket"...
Mar 27 09:02:12 volumio volumio[1468]: info: Starting Socket.io Server version 1.7.4
Mar 27 09:02:12 volumio volumio[1468]: info: Loading plugin "touch_display"...
Mar 27 09:02:12 volumio volumio[1468]: info: Applying required configuration parameters for plugin touch_display
Mar 27 09:02:12 volumio volumio[1679]: Starting albumart workers
Mar 27 09:02:12 volumio volumio[1468]: info: Loading i18n strings for locale en
Mar 27 09:02:12 volumio volumio[1678]: Starting albumart workers
Mar 27 09:02:12 volumio volumio[1468]: Updating browse sources language
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 27 09:02:12 volumio volumio[1677]: Starting albumart workers
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::initPlayerControls
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: Express server listening on port 3000
Mar 27 09:02:12 volumio volumio[1468]: [Metrics] WebUI: 10s 57.54ms
Mar 27 09:02:12 volumio volumio[1468]: info: CoreStateMachine::resetVolumioState
Mar 27 09:02:12 volumio volumio[1468]: info: CoreStateMachine::getcurrentVolume
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::volumioRetrievevolume
Mar 27 09:02:12 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:12 volumio volumio[1468]: info: Volumio Network Manager: Network status updated: 0
Mar 27 09:02:12 volumio volumio[1468]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.151 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 1
Mar 27 09:02:13 volumio volumio[1468]: verbose: New Socket.io Connection to volumio.local from 192.168.1.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Mar 27 09:02:13 volumio volumio[1468]: info: VolumeController:: Volume=41 Mute =false
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::pushState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioPushState
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::updateTrackBlock
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrackBlock
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioRetrievevolume
Mar 27 09:02:13 volumio volumio[1468]: verbose: New Socket.io Connection to volumio.local from 192.168.1.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Mar 27 09:02:13 volumio volumio[1468]: info: Setting Device type: Raspberry PI
Mar 27 09:02:13 volumio volumio[1468]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Mar 27 09:02:13 volumio volumio[1468]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170
Mar 27 09:02:13 volumio volumio[1468]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Mar 27 09:02:13 volumio volumio[1468]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Mar 27 09:02:13 volumio volumio[1468]: info: VolumeController:: Volume=41 Mute =false
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::pushState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioPushState
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: Discovery: adding ba024571-5aa0-494a-8db4-5bb0b9d51b99
Mar 27 09:02:13 volumio volumio[1468]: info: Discovery: Found device Volumio
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: Discovery: this is already registered, ba024571-5aa0-494a-8db4-5bb0b9d51b99
Mar 27 09:02:13 volumio volumio[1468]: info: Discovery: Found device Volumio
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetQueue
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::getQueue
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getQueue
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel
Mar 27 09:02:13 volumio volumio[1468]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction
Mar 27 09:02:13 volumio volumio[1468]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs
Mar 27 09:02:13 volumio volumio[1468]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetQueue
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::getQueue
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getQueue
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel
Mar 27 09:02:13 volumio volumio[1468]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction
Mar 27 09:02:13 volumio volumio[1468]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs
Mar 27 09:02:13 volumio volumio[1468]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection
Mar 27 09:02:13 volumio volumio[1468]: info: Completed loading Core Plugins
Mar 27 09:02:13 volumio volumio[1468]: info: Preparing to generate the ALSA configuration file
Mar 27 09:02:13 volumio volumio[1468]: info: Reloading queue from file
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::setRepeat null single undefined
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::pushState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioPushState
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::setRandom null
Mar 27 09:02:13 volumio volumio[1468]: info: CoreStateMachine::pushState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioPushState
Mar 27 09:02:13 volumio volumio[1468]: info: Asound.conf file unchanged, so no further update is needed
Mar 27 09:02:13 volumio volumio[1468]: info: Output device has changed, restarting MPD
Mar 27 09:02:13 volumio volumio[1468]: info: Output device has changed, restarting Shairport Sync
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:13 volumio sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 27 09:02:13 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 27 09:02:13 volumio sudo[1747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:13 volumio volumio[1468]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 27 09:02:13 volumio volumio[1468]: info: ___________ START PLUGINS ___________
Mar 27 09:02:13 volumio sudo[1755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Mar 27 09:02:13 volumio sudo[1755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Starting plugin
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Loaded bundled presets v1.5.5
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Detected model: Raspberry Pi 5 Model B Rev 1.0
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Detected SoC: bcm2712
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Detected RAM: 3997 MB
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 5","model_raw":"Raspberry Pi 5 Model B Rev 1.0","soc":"bcm2712","ram_mb":3997,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi5","is_pi":true}
Mar 27 09:02:13 volumio volumio[1468]: info: ControllerMpd::onStart: Initializing MPD
Mar 27 09:02:13 volumio volumio[1468]: info: Creating MPD Configuration file
Mar 27 09:02:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Mar 27 09:02:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Mar 27 09:02:13 volumio sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 27 09:02:13 volumio sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: [1774602133448] CoreMusicLibrary::Adding element Media Servers
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 27 09:02:13 volumio sudo[1759]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:13 volumio volumio[1468]: info: UPNP Browser: Client initialized successfully
Mar 27 09:02:13 volumio sudo[1763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 27 09:02:13 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:13 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Mar 27 09:02:13 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:13 volumio systemd[1]: mpd.service: Deactivated successfully.
Mar 27 09:02:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Mar 27 09:02:13 volumio systemd[1]: mpd.socket: Deactivated successfully.
Mar 27 09:02:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Mar 27 09:02:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Mar 27 09:02:13 volumio volumio[1468]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Mar 27 09:02:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Mar 27 09:02:13 volumio volumio[1468]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: [1774602133482] CoreMusicLibrary::Adding element Last_100
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 27 09:02:13 volumio volumio[1468]: info: [1774602133485] CoreMusicLibrary::Adding element Webradio
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 27 09:02:13 volumio volumio[1468]: info: Initializing BBC Radios
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: Loading i18n strings for locale en
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 27 09:02:13 volumio volumio[1468]: info: Volumio Calling Home
Mar 27 09:02:13 volumio sudo[1775]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 27 09:02:13 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 09:02:13 volumio sudo[1790]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Mar 27 09:02:13 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Detected prior configuration (backup=true, banner=true) - restoring wizard_complete
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Boot validation - {"valid":true,"drift_detected":false,"missing_videoconfig":false,"missing_include":false,"config_mismatch":false,"volumioconfig_mismatch":false,"errors":[]}
Mar 27 09:02:13 volumio volumio[1468]: info: pi_screen_setup: Management server started on port 4567
Mar 27 09:02:13 volumio sudo[1796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Mar 27 09:02:13 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Mar 27 09:02:13 volumio sudo[1798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Mar 27 09:02:13 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio systemd[1]: Reloading.
Mar 27 09:02:13 volumio volumio[1468]: info: touch_display: Backlight interface detected.
Mar 27 09:02:13 volumio volumio[1468]: info: MPD Permissions set
Mar 27 09:02:13 volumio volumio[1468]: info: MPD Permissions set
Mar 27 09:02:13 volumio volumio[1468]: info: Volumio called home
Mar 27 09:02:13 volumio volumio[1468]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:13 volumio volumio[1468]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Mar 27 09:02:13 volumio volumio[1468]: info: Starting Shairport Sync
Mar 27 09:02:13 volumio sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/11-0045/brightness
Mar 27 09:02:13 volumio sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Mar 27 09:02:13 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio volumio[1468]: info: Starting Shairport Sync
Mar 27 09:02:13 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:13 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:13 volumio volumio[1468]: info: Starting Shairport Sync
Mar 27 09:02:13 volumio sudo[1846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 27 09:02:13 volumio sudo[1844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 27 09:02:13 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio sudo[1848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 27 09:02:13 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:13 volumio volumio[1468]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Mar 27 09:02:13 volumio volumio[1468]: info: touch_display: File permissions for backlight brightness control set.
Mar 27 09:02:13 volumio volumio[1468]: error: touch_display: Error setting display brightness: Error: EINVAL: invalid argument, write
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio5-onboarding[1766]: time=2026-03-27T09:02:13.935Z level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z
Mar 27 09:02:13 volumio volumio[1468]: 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: 6
Mar 27 09:02:13 volumio volumio[1468]: 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: 6
Mar 27 09:02:13 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:02:13 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:02:13 volumio volumio5-onboarding[1766]: time=2026-03-27T09:02:13.971Z level=INFO msg="system info for 713f6332246a30fa6dc636f40d467cfb" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103
Mar 27 09:02:13 volumio volumio5-onboarding[1766]: time=2026-03-27T09:02:13.991Z level=INFO msg="bootstrapping state" hasInternet=true
Mar 27 09:02:13 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:02:13 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:13 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:02:14 volumio volumio[1468]: info: Volumio Network Manager: Network status updated: 1
Mar 27 09:02:14 volumio sudo[1800]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:14 volumio volumio[1468]: info: touch_display: systemctl daemon-reload succeeded.
Mar 27 09:02:14 volumio sudo[1872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Mar 27 09:02:14 volumio sudo[1872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:14 volumio systemd[1]: Reloading.
Mar 27 09:02:15 volumio sudo[1895]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 27 09:02:15 volumio sudo[1895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:15 volumio sudo[1897]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Mar 27 09:02:15 volumio sudo[1895]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio sudo[1897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:15 volumio sudo[1893]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 27 09:02:15 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:15 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio sudo[1798]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio volumio[1468]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Mar 27 09:02:15 volumio sudo[1872]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio volumio[1468]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service
Mar 27 09:02:15 volumio volumio[1468]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found.
Mar 27 09:02:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 27 09:02:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 27 09:02:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 27 09:02:15 volumio systemd[1]: shairport-sync.service: Consumed 1.605s CPU time.
Mar 27 09:02:15 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio volumio[1468]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Mar 27 09:02:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 27 09:02:15 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:15 volumio volumio[1468]: info: Shairport-Sync Started
Mar 27 09:02:15 volumio volumio[1468]: Error adding Membership: Error: addMembership EINVAL
Mar 27 09:02:15 volumio volumio[1468]: info: Shairport-Sync Started
Mar 27 09:02:15 volumio volumio[1468]: info: Shairport-Sync Started
Mar 27 09:02:17 volumio mpd[1792]: 2026-03-27T09:02:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 27 09:02:17 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Mar 27 09:02:17 volumio sudo[1747]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:17 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:17 volumio volumio[1468]: info: Completed starting Core Plugins
Mar 27 09:02:17 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:17 volumio volumio[1468]: info: ----- MyVolumio plugins startup ----
Mar 27 09:02:17 volumio volumio[1468]: info: -------------------------------------------
Mar 27 09:02:17 volumio volumio[1468]: info: [MyVolumio PluginManager] Fetching plans data....
Mar 27 09:02:17 volumio volumio[1468]: error: MPD error: The expression evaluated to a falsy value:
Mar 27 09:02:17 volumio volumio[1468]: assert.ok(self.idling)
Mar 27 09:02:17 volumio volumio[1468]: error: The expression evaluated to a falsy value:
Mar 27 09:02:17 volumio volumio[1468]: assert.ok(self.idling)
Mar 27 09:02:17 volumio volumio[1468]: info: MPD running with PID1792
Mar 27 09:02:17 volumio volumio[1468]: ,establishing connection
Mar 27 09:02:17 volumio volumio[1468]: error: MPD error: The expression evaluated to a falsy value:
Mar 27 09:02:17 volumio volumio[1468]: assert.ok(self.idling)
Mar 27 09:02:17 volumio volumio[1468]: error: The expression evaluated to a falsy value:
Mar 27 09:02:17 volumio volumio[1468]: assert.ok(self.idling)
Mar 27 09:02:17 volumio volumio[1468]: error: updateQueue error: null
Mar 27 09:02:21 volumio volumio-remote-updater[1112]: [2026-03-27 09:02:21] [connect] Successful connection
Mar 27 09:02:21 volumio volumio-remote-updater[1112]: [2026-03-27 09:02:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774602141 101
Mar 27 09:02:21 volumio volumio[1468]: 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: 7
Mar 27 09:02:22 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Mar 27 09:02:25 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Mar 27 09:02:25 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 27 09:02:25 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 27 09:02:25 volumio sudo[1897]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:25 volumio volumio[1468]: info: Upmpdcli Daemon Started
Mar 27 09:02:25 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin multiroom to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin qobuzconnect to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 27 09:02:26 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:26 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:26 volumio volumio[1468]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 27 09:02:26 volumio volumio[1468]: info: MyVolumio login type: Token
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 27 09:02:26 volumio volumio[1468]: info: Streaming services startup
Mar 27 09:02:26 volumio volumio[1468]: info: Starting Streaming Daemon
Mar 27 09:02:26 volumio sudo[1955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 27 09:02:26 volumio sudo[1955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:26 volumio volumio[1468]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 27 09:02:26 volumio sudo[1955]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:26 volumio volumio[1468]: error: Cannot start Volumio Streaming Daemon
Mar 27 09:02:26 volumio volumio[1468]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 27 09:02:26 volumio volumio[1468]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 27 09:02:27 volumio volumio[1468]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Mar 27 09:02:27 volumio volumio[1468]: info: MyVolumio token set successfully
Mar 27 09:02:27 volumio volumio[1468]: info: MYVOLUMIO: Adding device
Mar 27 09:02:27 volumio volumio[1468]: info: MYVOLUMIO: Evaluating Server
Mar 27 09:02:27 volumio volumio[1468]: info: MyVolumio status changed
Mar 27 09:02:27 volumio volumio[1468]: info: Streaming services startup
Mar 27 09:02:27 volumio volumio[1468]: info: Starting Streaming Daemon
Mar 27 09:02:27 volumio volumio[1468]: info: Removing browser output: myVolumio user plan is not superstar
Mar 27 09:02:27 volumio volumio[1468]: info: Removing audio output:
Mar 27 09:02:27 volumio volumio[1468]: info: Stoppping Tunnel 1
Mar 27 09:02:27 volumio sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 27 09:02:27 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:27 volumio sudo[1984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Mar 27 09:02:27 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:02:27 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:27 volumio volumio[1468]: error: Cannot start Volumio Streaming Daemon
Mar 27 09:02:27 volumio volumio[1468]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 27 09:02:27 volumio volumio[1468]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Mar 27 09:02:27 volumio sudo[1984]: pam_unix(sudo:session): session closed for user root
Mar 27 09:02:27 volumio volumio[1468]: info: Remote SSH Stopped
Mar 27 09:02:28 volumio volumio[1468]: info: Setting Geolocation for MyVolumio to eu3
Mar 27 09:02:28 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:28 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:28 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:28 volumio volumio[1468]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 27 09:02:29 volumio volumio[1468]: info: Updating MyVolumio device info
Mar 27 09:02:29 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:29 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:29 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:29 volumio volumio[1468]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 27 09:02:32 volumio volumio[1468]: info: MYVOLUMIO: Adding device
Mar 27 09:02:32 volumio volumio[1468]: info: MYVOLUMIO: Evaluating Server
Mar 27 09:02:32 volumio volumio[1468]: info: Setting Geolocation for MyVolumio to eu2
Mar 27 09:02:32 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:32 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:32 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:33 volumio volumio[1468]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 27 09:02:33 volumio volumio[1468]: info: Updating MyVolumio device info
Mar 27 09:02:33 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:33 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:33 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:33 volumio volumio[1468]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 27 09:02:34 volumio upmpdcli[2019]: writing RSA key
Mar 27 09:02:37 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Mar 27 09:02:38 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Mar 27 09:02:38 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Mar 27 09:02:41 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 27 09:02:41 volumio volumio[1468]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Mar 27 09:02:41 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:41 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 27 09:02:43 volumio volumio-remote-updater[1112]: Test mode disabled
Mar 27 09:02:43 volumio volumio-remote-updater[1112]: Alpha mode disabled
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 27 09:02:43 volumio volumio-remote-updater[1112]: Alpha legacy test mode disabled
Mar 27 09:02:43 volumio volumio[1468]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for missing PWM backlight module
\n- Fix for crash loop on Onboarding plugin
\n
\nNEW ADDITIONS
\n\n- Bump RPi/CM4 kernel to 6.12.74
\n- Bump Vivaldi to 7.5 for Kiosk Mode
\n
\n","title":"Update v4.119","updateavailable":true}
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:43 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 09:02:43 volumio volumio5-onboarding[1766]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded
Mar 27 09:02:43 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE
Mar 27 09:02:43 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'.
Mar 27 09:02:44 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1.
Mar 27 09:02:44 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server.
Mar 27 09:02:44 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.142Z level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z
Mar 27 09:02:44 volumio volumio[1468]: 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: 8
Mar 27 09:02:44 volumio volumio[1468]: 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: 8
Mar 27 09:02:44 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:02:44 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:44 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.152Z level=INFO msg="system info for 713f6332246a30fa6dc636f40d467cfb" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.166Z level=INFO msg="bootstrapping state" hasInternet=true
Mar 27 09:02:44 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:02:44 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:44 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:02:44 volumio volumio-remote-updater[1112]: Test mode disabled
Mar 27 09:02:44 volumio volumio-remote-updater[1112]: Alpha mode disabled
Mar 27 09:02:44 volumio volumio-remote-updater[1112]: Alpha legacy test mode disabled
Mar 27 09:02:44 volumio volumio[1468]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for missing PWM backlight module
\n- Fix for crash loop on Onboarding plugin
\n
\nNEW ADDITIONS
\n\n- Bump RPi/CM4 kernel to 6.12.74
\n- Bump Vivaldi to 7.5 for Kiosk Mode
\n
\n","title":"Update v4.119","updateavailable":true}
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.512Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory"
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.513Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory"
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.513Z level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory"
Mar 27 09:02:44 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:44.513Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory"
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:02:44 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 09:02:45 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:45.344Z level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=OR5FlQplMEMTd3LHjvIbPvZBwK92 tokenExpiry=2026-03-27T10:02:45.344Z
Mar 27 09:02:46 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:02:46 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:02:46 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Mar 27 09:02:46 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:46.351Z level=INFO msg="enabling local network discovery"
Mar 27 09:02:46 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:46.357Z level=INFO msg="enabling BLE discovery"
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 27 09:02:46 volumio volumio[1468]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Mar 27 09:02:46 volumio volumio[1468]: info: Completed starting MyVolumio Plugin
Mar 27 09:02:46 volumio volumio[1468]: [Metrics] CommandRouter: 43s 839.70ms
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::Close All Modals sent
Mar 27 09:02:46 volumio volumio[1468]: info: CoreCommandRouter::Close All Modals sent
Mar 27 09:02:47 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:47.366Z level=INFO msg="service successfully established" component=discovery/localnet
Mar 27 09:02:47 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 27 09:02:47 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 27 09:02:47 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 27 09:02:52 volumio bluealsa[1236]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_72_5D_C2_E8_78_47, ...)
Mar 27 09:02:53 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:53.920Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.151:56609
Mar 27 09:02:53 volumio volumio[1468]: info: BOOT COMPLETED
Mar 27 09:02:54 volumio volumio5-onboarding[2073]: time=2026-03-27T09:02:54.258Z level=INFO msg="new address was allocated" component=ble/conn old=1 new=2
Mar 27 09:02:54 volumio dbus-daemon[1096]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.20" (uid=0 pid=2073 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.1" (uid=0 pid=1095 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Mar 27 09:03:13 volumio volumio[1468]: info: UPDATER: Scheduling automatic update
Mar 27 09:03:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Mar 27 09:03:13 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Mar 27 09:03:13 volumio volumio[1468]: info: UPDATER: Auto update will take place at: Sat Mar 28 2026 04:07:08 GMT+0000 (Greenwich Mean Time)
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.065Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.151:56609 @ 0x272a8d0" latency=21.705799ms platform=PLATFORM_IOS version=5.260209.0
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.066Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.151:56609 @ 0x272a8d0" latency=21.567427ms timeout=10s
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.066Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0"
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Mar 27 09:03:19 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:03:19 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:19 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.069Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" name=Volumio
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.071Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" language=en
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.072Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" timezone=Europe/London
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.072Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" available=true connected=true macAddress=2c:cf:67:3f:b3:dc ip4Address=192.168.1.137/24 ip6Address=
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.073Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.073Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" setupComplete=true
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 27 09:03:19 volumio volumio[1468]: amixer -c 0 info | grep "vc4-hdmi-0"
Mar 27 09:03:19 volumio volumio[1468]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0'
Mar 27 09:03:19 volumio volumio[1468]: amixer -c 1 info | grep "vc4-hdmi-1"
Mar 27 09:03:19 volumio volumio[1468]: Card sysdefault:1 'vc4hdmi1'/'vc4-hdmi-1'
Mar 27 09:03:19 volumio volumio[1468]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5
Mar 27 09:03:19 volumio volumio[1468]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Mar 27 09:03:19 volumio volumio[1468]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Mar 27 09:03:19 volumio volumio[1468]: amixer -c 5 info | grep "AudioQuest DragonFly Red v1.0"
Mar 27 09:03:19 volumio volumio[1468]: Card sysdefault:5 'v10'/'AudioQuest AudioQuest DragonFly Red v1.0 at usb-xhci-hcd.1-1, full speed'
Mar 27 09:03:19 volumio volumio[1468]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5
Mar 27 09:03:19 volumio volumio[1468]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Mar 27 09:03:19 volumio volumio[1468]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Mar 27 09:03:19 volumio volumio[1468]: amixer -c 5 info | grep "AudioQuest DragonFly Red v1.0"
Mar 27 09:03:19 volumio volumio[1468]: Card sysdefault:5 'v10'/'AudioQuest AudioQuest DragonFly Red v1.0 at usb-xhci-hcd.1-1, full speed'
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.132Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" selectedOutputId=5
Mar 27 09:03:19 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:03:19 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:19 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.143Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" currentVersion=4.103 latestVersion=4.119
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.143Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" status=UPDATE_STATUS_NONE progress=0
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.143Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" userId=OR5FlQplMEMTd3LHjvIbPvZBwK92
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.143Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" providers=9
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.143Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" plugins=62
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:19 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.146Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" state=STATUS_STOPPED positionMs=0 volume=41
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.146Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.151:56609 @ 0x272a8d0" id="mnt/USB/FA14-0D7F/Mick's Music/AC_DC/AC_DC - 1994 - If You Want Blood Youve Got It/01. Riff Raff.flac" title="Riff Raff"
Mar 27 09:03:19 volumio volumio5-onboarding[2073]: time=2026-03-27T09:03:19.168Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.151:56609,00:00:00:00:00:00%01 @ 0x272a8d0" latency=19.764426ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE
Mar 27 09:03:19 volumio sudo[2159]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 27 09:03:19 volumio sudo[2159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:19 volumio sudo[2161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 27 09:03:19 volumio sudo[2159]: pam_unix(sudo:session): session closed for user root
Mar 27 09:03:19 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:19 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root
Mar 27 09:03:19 volumio volumio[1468]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.151 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9
Mar 27 09:03:19 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 27 09:03:20 volumio sudo[2166]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 27 09:03:20 volumio sudo[2166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:20 volumio sudo[2168]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 27 09:03:20 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:20 volumio sudo[2166]: pam_unix(sudo:session): session closed for user root
Mar 27 09:03:20 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 27 09:03:20 volumio volumio[1468]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.151 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:20 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 27 09:03:20 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:03:20 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:20 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:20 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:20 volumio volumio[1468]: info: Listing playlists
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 27 09:03:20 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 27 09:03:21 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:03:22 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:03:22 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:22 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:03:22 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 09:03:23 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 09:03:23 volumio volumio[1468]: info: Received Get System Info
Mar 27 09:03:23 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 09:03:23 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 09:03:23 volumio volumio[1468]: info: Discovery: Getting this device information
Mar 27 09:03:23 volumio volumio[1468]: info: CoreCommandRouter::volumioGetState
Mar 27 09:03:23 volumio volumio[1468]: info: CorePlayQueue::getTrack 0
Mar 27 09:03:23 volumio volumio[1468]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 09:03:28 volumio volumio[1468]: info: Starting Uninstall of plugin user_interface - touch_display
Mar 27 09:03:28 volumio volumio[1468]: info: Uninstalling plugin touch_display
Mar 27 09:03:28 volumio sudo[2187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Mar 27 09:03:28 volumio sudo[2187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:28 volumio sudo[2189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service
Mar 27 09:03:28 volumio sudo[2187]: pam_unix(sudo:session): session closed for user root
Mar 27 09:03:28 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:28 volumio volumio[1468]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Mar 27 09:03:28 volumio sudo[2191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service
Mar 27 09:03:28 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:28 volumio sudo[2195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service
Mar 27 09:03:28 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 09:03:28 volumio volumio[1468]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 27 09:03:28 volumio volumio[1468]: TypeError: Cannot read properties of undefined (reading 'split')
Mar 27 09:03:28 volumio volumio[1468]: at /data/plugins/user_interface/touch_display/index.js:1274:37
Mar 27 09:03:28 volumio volumio[1468]: at ChildProcess.exithandler (node:child_process:413:7)
Mar 27 09:03:28 volumio volumio[1468]: at ChildProcess.emit (node:events:514:28)
Mar 27 09:03:28 volumio volumio[1468]: at maybeClose (node:internal/child_process:1105:16)
Mar 27 09:03:28 volumio volumio[1468]: at Socket. (node:internal/child_process:457:11)
Mar 27 09:03:28 volumio volumio[1468]: at Socket.emit (node:events:514:28)
Mar 27 09:03:28 volumio volumio[1468]: at Pipe. (node:net:337:12)
Mar 27 09:03:28 volumio volumio[1468]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 27 09:03:28 volumio sudo[2195]: pam_unix(sudo:session): session closed for user root
Mar 27 09:03:28 volumio systemd[1]: Reloading.
Mar 27 09:03:28 volumio sudo[2229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 09:02'
Mar 27 09:03:28 volumio sudo[2229]: 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="dc61260dec5515dafd2b634881860b4c46c919ff"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026"
VOLUMIO_VERSION="4.103"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"