May 31 11:54:12 volumiobw systemd-journald[24]: Journal started May 31 11:54:12 volumiobw systemd-journald[24]: Runtime Journal (/run/log/journal/b3186e77aaf65b5897f20ee868385b72) is 3.7M, max 30.0M, 26.2M free. May 31 11:54:12 volumiobw systemd[1]: Finished systemd-sysusers.service - Create System Users. May 31 11:54:12 volumiobw systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 31 11:54:12 volumiobw systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 31 11:54:12 volumiobw systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 31 11:54:12 volumiobw systemd[1]: systemd-udevd.service - Rule-based Manager for Device Events and Files was skipped because of an unmet condition check (ConditionPathIsReadWrite=/sys). May 31 11:54:12 volumiobw systemd[1]: plymouth-start.service - Show Plymouth Boot Screen was skipped because of an unmet condition check (ConditionVirtualization=!container). May 31 11:54:12 volumiobw systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 31 11:54:12 volumiobw systemd[1]: systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch was skipped because of an unmet condition check (ConditionVirtualization=!container). May 31 11:54:12 volumiobw systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 31 11:54:12 volumiobw systemd[1]: Reached target paths.target - Path Units. May 31 11:54:13 volumiobw systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 31 11:54:13 volumiobw systemd[1]: Mounting var-log.mount - /var/log... May 31 11:54:13 volumiobw systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... May 31 11:54:13 volumiobw systemd[1]: Mounted var-log.mount - /var/log. May 31 11:54:13 volumiobw systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. May 31 11:54:13 volumiobw systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... May 31 11:54:13 volumiobw systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 31 11:54:13 volumiobw systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. May 31 11:54:13 volumiobw systemd[1]: Reached target local-fs.target - Local File Systems. May 31 11:54:13 volumiobw systemd[1]: Starting networking.service - Raise network interfaces... May 31 11:54:13 volumiobw systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... May 31 11:54:13 volumiobw systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because of an unmet condition check (ConditionPathIsMountPoint=/proc/sys/fs/binfmt_misc). May 31 11:54:13 volumiobw systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). May 31 11:54:13 volumiobw systemd-journald[24]: Runtime Journal (/run/log/journal/b3186e77aaf65b5897f20ee868385b72) is 3.7M, max 30.0M, 26.2M free. May 31 11:54:13 volumiobw systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 31 11:54:13 volumiobw systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 31 11:54:13 volumiobw ifup[54]: nl80211 not found. May 31 11:54:13 volumiobw systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. May 31 11:54:13 volumiobw systemd-tmpfiles[53]: fchmod() of /sys/kernel/debug failed: Read-only file system May 31 11:54:13 volumiobw systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 31 11:54:13 volumiobw ifup[67]: nl80211 not found. May 31 11:54:13 volumiobw systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... May 31 11:54:13 volumiobw systemd[1]: Starting rpcbind.service - RPC bind portmap service... May 31 11:54:13 volumiobw systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 31 11:54:13 volumiobw systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. May 31 11:54:13 volumiobw systemd[1]: Reached target rpc_pipefs.target. May 31 11:54:13 volumiobw systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). May 31 11:54:13 volumiobw systemd[1]: Reached target nfs-client.target - NFS client services. May 31 11:54:13 volumiobw systemd[1]: Started rpcbind.service - RPC bind portmap service. May 31 11:54:13 volumiobw systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 31 11:54:13 volumiobw systemd[1]: Reached target remote-fs.target - Remote File Systems. May 31 11:54:13 volumiobw systemd[1]: Reached target rpcbind.target - RPC Port Mapper. May 31 11:54:13 volumiobw systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 31 11:54:13 volumiobw systemd[1]: Reached target sysinit.target - System Initialization. May 31 11:54:13 volumiobw systemd[1]: Started apt-daily.timer - Daily apt download activities. May 31 11:54:13 volumiobw systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. May 31 11:54:13 volumiobw systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. May 31 11:54:13 volumiobw systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. May 31 11:54:13 volumiobw systemd[1]: fstrim.timer - Discard unused blocks once a week was skipped because of an unmet condition check (ConditionVirtualization=!container). May 31 11:54:13 volumiobw systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. May 31 11:54:13 volumiobw systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. May 31 11:54:13 volumiobw systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 31 11:54:13 volumiobw systemd[1]: Reached target timers.target - Timer Units. May 31 11:54:13 volumiobw systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. May 31 11:54:13 volumiobw systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 31 11:54:13 volumiobw systemd[1]: Listening on triggerhappy.socket. May 31 11:54:13 volumiobw systemd[1]: Reached target sockets.target - Socket Units. May 31 11:54:13 volumiobw systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 31 11:54:13 volumiobw systemd[1]: Reached target basic.target - Basic System. May 31 11:54:13 volumiobw systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 31 11:54:13 volumiobw systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 31 11:54:13 volumiobw systemd[1]: e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots was skipped because of an unmet condition check (ConditionCapability=CAP_SYS_RAWIO). May 31 11:54:13 volumiobw systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). May 31 11:54:13 volumiobw systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). May 31 11:54:13 volumiobw avahi-daemon[84]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 31 11:54:13 volumiobw avahi-daemon[84]: Successfully dropped root privileges. May 31 11:54:13 volumiobw avahi-daemon[84]: avahi-daemon 0.8 starting up. May 31 11:54:13 volumiobw systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... May 31 11:54:13 volumiobw systemd[1]: Started iptables.service - Volumio Iptables Module. May 31 11:54:13 volumiobw systemd[1]: Starting systemd-logind.service - User Login Management... May 31 11:54:13 volumiobw systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 31 11:54:13 volumiobw avahi-daemon[84]: Successfully called chroot(). May 31 11:54:13 volumiobw avahi-daemon[84]: Successfully dropped remaining capabilities. May 31 11:54:13 volumiobw avahi-daemon[84]: Loading service file /services/volumio.service. May 31 11:54:13 volumiobw systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... May 31 11:54:13 volumiobw avahi-daemon[84]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 31 11:54:13 volumiobw avahi-daemon[84]: New relevant interface lo.IPv4 for mDNS. May 31 11:54:13 volumiobw avahi-daemon[84]: Network interface enumeration completed. May 31 11:54:13 volumiobw avahi-daemon[84]: Registering new address record for 127.0.0.1 on lo.IPv4. May 31 11:54:13 volumiobw systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Unable to parse trigger line: May 31 11:54:13 volumiobw thd[90]: Found socket passed from systemd May 31 11:54:13 volumiobw systemd[1]: Started volumio.service - Volumio Backend Module. May 31 11:54:13 volumiobw systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). May 31 11:54:13 volumiobw systemd[1]: Started dbus.service - D-Bus System Message Bus. May 31 11:54:13 volumiobw systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. May 31 11:54:13 volumiobw volumio-remote-updater[91]: Volumio OTA Client: 1.7.0 May 31 11:54:13 volumiobw volumio-remote-updater[91]: Error: No active session May 31 11:54:13 volumiobw systemd[1]: iptables.service: Deactivated successfully. May 31 11:54:13 volumiobw volumio-remote-updater[91]: [2025-05-31 11:54:13] [info] asio async_connect error: asio.system:111 (Connection refused) May 31 11:54:13 volumiobw volumio-remote-updater[91]: [2025-05-31 11:54:13] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 31 11:54:13 volumiobw volumio-remote-updater[91]: [2025-05-31 11:54:13] [error] handle_connect error: Connection refused May 31 11:54:13 volumiobw systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 31 11:54:13 volumiobw ifup[100]: eth0 May 31 11:54:13 volumiobw dhcpcd[87]: dhcpcd-9.4.1 starting May 31 11:54:13 volumiobw ifup[115]: nl80211 not found. May 31 11:54:13 volumiobw dhcpcd[117]: dev: loaded udev May 31 11:54:13 volumiobw dhcpcd[117]: DUID 00:01:00:01:2f:cc:c7:3a:82:d4:10:ae:dc:6a May 31 11:54:13 volumiobw systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. May 31 11:54:13 volumiobw dhcpcd[117]: dhcp_vendor: No such process May 31 11:54:13 volumiobw dhcpcd[117]: no valid interfaces found May 31 11:54:13 volumiobw systemd-logind[89]: New seat seat0. May 31 11:54:13 volumiobw systemd[1]: Started systemd-logind.service - User Login Management. May 31 11:54:13 volumiobw ifup[126]: Cannot find device "host0" May 31 11:54:13 volumiobw ifup[46]: ifup: failed to bring up host0 May 31 11:54:13 volumiobw systemd[1]: networking.service: Main process exited, code=exited, status=1/FAILURE May 31 11:54:13 volumiobw systemd[1]: networking.service: Failed with result 'exit-code'. May 31 11:54:13 volumiobw systemd[1]: Failed to start networking.service - Raise network interfaces. May 31 11:54:13 volumiobw systemd[1]: Reached target network.target - Network. May 31 11:54:13 volumiobw systemd[1]: Reached target network-online.target - Network is Online. May 31 11:54:14 volumiobw systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... May 31 11:54:14 volumiobw systemd[1]: Starting nmbd.service - Samba NMB Daemon... May 31 11:54:14 volumiobw systemd[1]: ntpsec.service - Network Time Service was skipped because of an unmet condition check (ConditionCapability=CAP_SYS_TIME). May 31 11:54:14 volumiobw systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... May 31 11:54:14 volumiobw systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... May 31 11:54:14 volumiobw systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... May 31 11:54:14 volumiobw sm-notify[156]: Version 2.6.2 starting May 31 11:54:14 volumiobw systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 31 11:54:14 volumiobw systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:54:14 volumiobw systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 31 11:54:14 volumiobw systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. May 31 11:54:14 volumiobw systemd[1]: Starting welcome.service - Show a welcome message on console... May 31 11:54:14 volumiobw systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. May 31 11:54:14 volumiobw welcome[166]: Resolved ip:[0] May 31 11:54:14 volumiobw dbus-daemon[85]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.3' (uid=0 pid=168 comm="timedatectl show --property=NTPSynchronized --valu") May 31 11:54:14 volumiobw systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. May 31 11:54:14 volumiobw systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 31 11:54:14 volumiobw systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... May 31 11:54:14 volumiobw systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 31 11:54:14 volumiobw systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 31 11:54:14 volumiobw systemd[1]: Finished welcome.service - Show a welcome message on console. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: ifplugd 0.28 initializing. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: Using interface eth0/82:D4:10:AE:DC:6A with driver (version: 1.0) May 31 11:54:14 volumiobw systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: Using detection mode: SIOCETHTOOL May 31 11:54:14 volumiobw ifplugd(eth0)[202]: Initialization complete, link beat detected. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: Could not open /dev/tty, cannot beep. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 31 11:54:14 volumiobw systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. May 31 11:54:14 volumiobw systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 31 11:54:14 volumiobw systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 31 11:54:14 volumiobw systemd[1]: Started console-getty.service - Console Getty. May 31 11:54:14 volumiobw systemd[1]: getty@tty1.service - Getty on tty1 was skipped because of an unmet condition check (ConditionPathExists=/dev/tty0). May 31 11:54:14 volumiobw systemd[1]: Reached target getty.target - Login Prompts. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: client: nl80211 not found. May 31 11:54:14 volumiobw avahi-daemon[84]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.83. May 31 11:54:14 volumiobw avahi-daemon[84]: New relevant interface eth0.IPv4 for mDNS. May 31 11:54:14 volumiobw avahi-daemon[84]: Registering new address record for 192.168.1.83 on eth0.IPv4. May 31 11:54:14 volumiobw systemd[1]: welcome.service: Deactivated successfully. May 31 11:54:14 volumiobw systemd[1]: Stopped welcome.service - Show a welcome message on console. May 31 11:54:14 volumiobw systemd[1]: Stopping welcome.service - Show a welcome message on console... May 31 11:54:14 volumiobw nmbd[215]: [2025/05/31 11:54:14.544026, 0] ../../source3/nmbd/nmbd.c:901(main) May 31 11:54:14 volumiobw nmbd[215]: nmbd version 4.17.8-Raspbian started. May 31 11:54:14 volumiobw nmbd[215]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 31 11:54:14 volumiobw nmbd[215]: [2025/05/31 11:54:14.554536, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 31 11:54:14 volumiobw systemd[1]: Starting welcome.service - Show a welcome message on console... May 31 11:54:14 volumiobw nmbd[215]: started asyncdns process 235 May 31 11:54:14 volumiobw systemd[1]: Started nmbd.service - Samba NMB Daemon. May 31 11:54:14 volumiobw dbus-daemon[85]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 11:54:14 volumiobw bash[165]: Time is already synchronized. May 31 11:54:14 volumiobw systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 31 11:54:14 volumiobw systemd[1]: setdatetime-helper.service: Deactivated successfully. May 31 11:54:14 volumiobw systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 31 11:54:14 volumiobw welcome[233]: Resolved ip:[1] 192.168.1.83 May 31 11:54:14 volumiobw systemd[1]: Started systemd-timedated.service - Time & Date Service. May 31 11:54:14 volumiobw systemd[1]: Finished welcome.service - Show a welcome message on console. May 31 11:54:14 volumiobw systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 31 11:54:14 volumiobw ifplugd(eth0)[202]: Program executed successfully. May 31 11:54:14 volumiobw ifplugd[150]: Network Interface Plugging Daemon...start eth0...done. May 31 11:54:14 volumiobw systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. May 31 11:54:14 volumiobw winbindd[256]: [2025/05/31 11:54:14.823773, 0] ../../source3/winbindd/winbindd.c:1440(main) May 31 11:54:14 volumiobw winbindd[256]: winbindd version 4.17.8-Raspbian started. May 31 11:54:14 volumiobw winbindd[256]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 31 11:54:14 volumiobw winbindd[256]: [2025/05/31 11:54:14.834191, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 31 11:54:14 volumiobw winbindd[256]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 31 11:54:14 volumiobw systemd[1]: Started winbind.service - Samba Winbind Daemon. May 31 11:54:14 volumiobw systemd[1]: Starting smbd.service - Samba SMB Daemon... May 31 11:54:15 volumiobw avahi-daemon[84]: Server startup complete. Host name is volumiobw.local. Local service cookie is 3303328000. May 31 11:54:15 volumiobw smbd[282]: [2025/05/31 11:54:15.482649, 0] ../../source3/smbd/server.c:1741(main) May 31 11:54:15 volumiobw smbd[282]: smbd version 4.17.8-Raspbian started. May 31 11:54:15 volumiobw smbd[282]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 31 11:54:15 volumiobw volumio[92]: info: ------------------------------------------- May 31 11:54:15 volumiobw volumio[92]: info: ----- Volumio3 ---- May 31 11:54:15 volumiobw volumio[92]: info: ------------------------------------------- May 31 11:54:15 volumiobw volumio[92]: info: ----- System startup ---- May 31 11:54:15 volumiobw volumio[92]: info: ------------------------------------------- May 31 11:54:15 volumiobw systemd[1]: Started smbd.service - Samba SMB Daemon. May 31 11:54:15 volumiobw systemd[1]: Reached target multi-user.target - Multi-User System. May 31 11:54:15 volumiobw systemd[1]: Reached target graphical.target - Graphical Interface. May 31 11:54:15 volumiobw systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 31 11:54:15 volumiobw systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 31 11:54:15 volumiobw systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 31 11:54:15 volumiobw systemd[1]: Startup finished in 3.584s. May 31 11:54:15 volumiobw volumio[92]: info: MYVOLUMIO Environment detected May 31 11:54:15 volumiobw volumio[92]: info: Plugin folders cleanup May 31 11:54:15 volumiobw volumio[92]: info: Scanning into folder /volumio/app/plugins/ May 31 11:54:15 volumiobw volumio[92]: info: Scanning category audio_interface May 31 11:54:15 volumiobw volumio[92]: info: Scanning category miscellanea May 31 11:54:15 volumiobw volumio[92]: info: Scanning category music_service May 31 11:54:15 volumiobw volumio[92]: info: Scanning category plugins.json May 31 11:54:15 volumiobw volumio[92]: info: Scanning category system_controller May 31 11:54:15 volumiobw volumio[92]: info: Scanning category user_interface May 31 11:54:15 volumiobw volumio[92]: info: Scanning into folder /data/plugins/ May 31 11:54:15 volumiobw volumio[92]: info: Scanning category audio_interface May 31 11:54:15 volumiobw volumio[92]: info: Scanning category music_service May 31 11:54:15 volumiobw volumio[92]: info: Plugin folders cleanup completed May 31 11:54:15 volumiobw volumio[92]: info: ------------------------------------------- May 31 11:54:15 volumiobw volumio[92]: info: ----- Core plugins startup ---- May 31 11:54:15 volumiobw volumio[92]: info: ------------------------------------------- May 31 11:54:15 volumiobw volumio[92]: info: Loading plugins from folder /volumio/app/plugins/ May 31 11:54:15 volumiobw volumio[92]: info: Adding plugin upnp to MyMusic Plugins May 31 11:54:15 volumiobw volumio[92]: info: Adding plugin airplay_emulation to MyMusic Plugins May 31 11:54:15 volumiobw volumio[92]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 11:54:15 volumiobw volumio[92]: info: Loading plugins from folder /data/plugins/ May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "system"... May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "appearance"... May 31 11:54:16 volumiobw avahi-daemon[84]: Service "VolumioBW" (/services/volumio.service) successfully established. May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "network"... May 31 11:54:16 volumiobw volumio[92]: info: Refreshing Cached IP Addresses May 31 11:54:16 volumiobw sudo[305]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 11:54:16 volumiobw sudo[305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:54:16 volumiobw sudo[307]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 11:54:16 volumiobw sudo[307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:54:16 volumiobw sudo[305]: pam_unix(sudo:session): session closed for user root May 31 11:54:16 volumiobw sudo[307]: pam_unix(sudo:session): session closed for user root May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "services"... May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "alsa_controller"... May 31 11:54:16 volumiobw sudo[319]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 11:54:16 volumiobw sudo[319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:54:16 volumiobw sudo[319]: pam_unix(sudo:session): session closed for user root May 31 11:54:16 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "wizard"... May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "networkfs"... May 31 11:54:16 volumiobw volumio[92]: info: Starting Udev Watcher for removable devices May 31 11:54:16 volumiobw volumio[92]: error: Cannot associate FS Label, not mounting May 31 11:54:16 volumiobw volumio[92]: error: Cannot associate FS Label, not mounting May 31 11:54:16 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "volumio_command_line_client"... May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "upnp"... May 31 11:54:16 volumiobw volumio[92]: info: [1748685256615] Starting Upmpd Daemon May 31 11:54:16 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "my_music"... May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "mpd"... May 31 11:54:16 volumiobw volumio[92]: info: Plugin upnp_browser is not enabled May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "alarm-clock"... May 31 11:54:16 volumiobw volumio[92]: info: Plugin airplay_emulation is not enabled May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "last_100"... May 31 11:54:16 volumiobw volumio[92]: info: Loading plugin "webradio"... May 31 11:54:17 volumiobw volumio[92]: info: Loading plugin "i2s_dacs"... May 31 11:54:17 volumiobw volumio[92]: info: I2S DAC not set, start Auto-detection May 31 11:54:17 volumiobw volumio[92]: info: Loading plugin "volumiodiscovery"... May 31 11:54:17 volumiobw volumio[92]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 11:54:17 volumiobw volumio[92]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 11:54:17 volumiobw volumio[92]: *** WARNING *** For more information see May 31 11:54:17 volumiobw volumio[92]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 11:54:17 volumiobw volumio[92]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 11:54:17 volumiobw node[92]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 11:54:17 volumiobw volumio[92]: *** WARNING *** For more information see May 31 11:54:17 volumiobw node[92]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 11:54:17 volumiobw node[92]: *** WARNING *** For more information see May 31 11:54:17 volumiobw node[92]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 11:54:17 volumiobw node[92]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 11:54:17 volumiobw node[92]: *** WARNING *** For more information see May 31 11:54:17 volumiobw volumio[92]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 11:54:17 volumiobw volumio[92]: info: Discovery: Started advertising with name: VolumioBW May 31 11:54:17 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 11:54:17 volumiobw volumio[92]: info: Loading plugin "ytcr"... May 31 11:54:18 volumiobw volumio-remote-updater[91]: [2025-05-31 11:54:18] [connect] Successful connection May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "outputs"... May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "albumart"... May 31 11:54:19 volumiobw volumio[92]: info: Plugin example_plugin is not enabled May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "inputs"... May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "updater_comm"... May 31 11:54:19 volumiobw volumio[92]: info: Plugin mpdemulation is not enabled May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "rest_api"... May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "websocket"... May 31 11:54:19 volumiobw volumio[92]: info: Starting Socket.io Server version 1.7.4 May 31 11:54:19 volumiobw volumio[92]: info: Loading plugin "fusiondsp"... May 31 11:54:19 volumiobw volumio[92]: info: Applying required configuration parameters for plugin fusiondsp May 31 11:54:19 volumiobw volumio[340]: Forking 2 albumart workers May 31 11:54:19 volumiobw volumio[92]: info: Loading i18n strings for locale en May 31 11:54:19 volumiobw volumio[92]: Updating browse sources language May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::initPlayerControls May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:54:19 volumiobw volumio[92]: Express server listening on port 3000 May 31 11:54:19 volumiobw volumio[92]: [Metrics] WebUI: 4s 745.00ms May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::resetVolumioState May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::getcurrentVolume May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::volumioRetrievevolume May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:54:19 volumiobw volumio[92]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 31 11:54:19 volumiobw volumio[92]: info: Completed loading Core Plugins May 31 11:54:19 volumiobw volumio[92]: info: Preparing to generate the ALSA configuration file May 31 11:54:19 volumiobw volumio[92]: info: Cannot read play queue from file May 31 11:54:19 volumiobw volumio[92]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 11:54:19 volumiobw volumio[92]: info: Reading ALSA contributions from plugins. May 31 11:54:19 volumiobw volumio[92]: info: Volumio Network Manager: Network status updated: 1 May 31 11:54:19 volumiobw volumio[92]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 31 11:54:19 volumiobw volumio[92]: wlan0 Interface doesn't support scanning. May 31 11:54:19 volumiobw volumio[92]: info: Cannot use regular scanning, forcing with ap-force May 31 11:54:19 volumiobw sudo[383]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 31 11:54:19 volumiobw sudo[383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:54:19 volumiobw sudo[383]: pam_unix(sudo:session): session closed for user root May 31 11:54:19 volumiobw volumio[92]: nl80211 not found. May 31 11:54:19 volumiobw volumio[92]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 31 11:54:19 volumiobw volumio[92]: nl80211 not found. May 31 11:54:19 volumiobw volumio[92]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 31 11:54:19 volumiobw volumio[92]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 31 11:54:19 volumiobw volumio[92]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 31 11:54:19 volumiobw volumio[92]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::setRepeat null single undefined May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::pushState May 31 11:54:19 volumiobw volumio[92]: info: CorePlayQueue::getTrack 0 May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::volumioPushState May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::setRandom null May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::pushState May 31 11:54:19 volumiobw volumio[92]: info: CorePlayQueue::getTrack 0 May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::volumioPushState May 31 11:54:19 volumiobw volumio[92]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 31 11:54:19 volumiobw volumio[92]: info: VolumeController:: Volume=19 Mute =false May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::pushState May 31 11:54:19 volumiobw volumio[92]: info: CorePlayQueue::getTrack 0 May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::volumioPushState May 31 11:54:19 volumiobw volumio[92]: info: CoreStateMachine::updateTrackBlock May 31 11:54:19 volumiobw volumio[92]: info: CorePlayQueue::getTrackBlock May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::volumioRetrievevolume May 31 11:54:19 volumiobw volumio-remote-updater[91]: [2025-05-31 11:54:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1748685258 101 May 31 11:54:19 volumiobw volumio[92]: 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: 5 May 31 11:54:19 volumiobw volumio[92]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 31 11:54:19 volumiobw volumio[92]: info: Discovery: adding ad9ffb61-9766-445e-952f-8fea89669fa3 May 31 11:54:19 volumiobw volumio[92]: info: Discovery: Found device VolumioBW May 31 11:54:19 volumiobw volumio[92]: info: CoreCommandRouter::volumioGetState May 31 11:54:19 volumiobw volumio[92]: info: CorePlayQueue::getTrack 0 May 31 11:54:20 volumiobw volumio[92]: info: Discovery: this is already registered, ad9ffb61-9766-445e-952f-8fea89669fa3 May 31 11:54:20 volumiobw volumio[92]: info: Discovery: Found device VolumioBW May 31 11:54:20 volumiobw volumio[92]: info: CoreCommandRouter::volumioGetState May 31 11:54:20 volumiobw volumio[92]: info: CorePlayQueue::getTrack 0 May 31 11:54:20 volumiobw volumio[92]: info: VolumeController:: Volume=19 Mute =false May 31 11:54:20 volumiobw volumio[92]: info: CoreStateMachine::pushState May 31 11:54:20 volumiobw volumio[92]: info: CorePlayQueue::getTrack 0 May 31 11:54:20 volumiobw volumio[92]: info: CoreCommandRouter::volumioPushState May 31 11:54:20 volumiobw volumio[351]: Starting albumart workers May 31 11:54:20 volumiobw volumio[92]: info: Asound.conf file written May 31 11:54:20 volumiobw volumio[350]: Starting albumart workers May 31 11:54:20 volumiobw sudo[397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 11:54:20 volumiobw sudo[397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:54:20 volumiobw sudo[397]: pam_unix(sudo:session): session closed for user root May 31 11:54:20 volumiobw volumio[92]: /bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted May 31 11:54:20 volumiobw volumio[92]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:54:20 volumiobw volumio[92]: Error: Command failed: /usr/bin/sudo /bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 11:54:20 volumiobw volumio[92]: /bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted May 31 11:54:20 volumiobw volumio[92]: at checkExecSyncError (node:child_process:887:11) May 31 11:54:20 volumiobw volumio[92]: at execSync (node:child_process:959:15) May 31 11:54:20 volumiobw volumio[92]: at /volumio/app/plugins/audio_interface/alsa_controller/index.js:2218:13 May 31 11:54:20 volumiobw volumio[92]: at /volumio/node_modules/graceful-fs/graceful-fs.js:143:16 May 31 11:54:20 volumiobw volumio[92]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 May 31 11:54:20 volumiobw volumio[92]: at FSReqCallback.oncomplete (node:fs:190:23) { May 31 11:54:20 volumiobw volumio[92]: status: 1, May 31 11:54:20 volumiobw volumio[92]: signal: null, May 31 11:54:20 volumiobw volumio[92]: output: [ May 31 11:54:20 volumiobw volumio[92]: null, May 31 11:54:20 volumiobw volumio[92]: '', May 31 11:54:20 volumiobw volumio[92]: "/bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted\n" May 31 11:54:20 volumiobw volumio[92]: ], May 31 11:54:20 volumiobw volumio[92]: pid: 396, May 31 11:54:20 volumiobw volumio[92]: stdout: '', May 31 11:54:20 volumiobw volumio[92]: stderr: "/bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted\n" May 31 11:54:20 volumiobw volumio[92]: } May 31 11:54:20 volumiobw volumio[92]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:54:20 volumiobw sudo[425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 11:53' May 31 11:54:20 volumiobw sudo[425]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"